builder: mozilla-central_ubuntu64_hw_test-dromaeojs-e10s slave: talos-linux64-ix-073 starttime: 1490959813.99 results: success (0) revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.990262) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.990730) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:13.991000) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:14.006070) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:14.006409) ========= 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=0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-03-31 04:30:14-- https://hg.mozilla.org/build/tools/raw-file/default/buildfarm/utils/archiver_client.py Resolving hg.mozilla.org (hg.mozilla.org)... 63.245.215.25, 63.245.215.102 Connecting to hg.mozilla.org (hg.mozilla.org)|63.245.215.25|:443... connected. HTTP request sent, awaiting response... 200 Script output follows Length: 12179 (12K) [text/x-python] Saving to: `archiver_client.py' 0K .......... . 100% 40.3M=0s 2017-03-31 04:30:14 (40.3 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.131853 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:14.152356) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:14.152676) ========= 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=0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020474 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:14.202343) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 04:30:14.202679) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --destination scripts --debug' in dir /builds/slave/test/. (timeout 1200 secs) watching logfiles {} argv: ['bash', '-c', 'python archiver_client.py mozharness --repo mozilla-central --rev 03d602fd723ad6ff4588c04855884ffa1dee9410 --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=0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-03-31 04:30:14,848 truncating revision to first 12 chars 2017-03-31 04:30:14,848 Setting DEBUG logging. 2017-03-31 04:30:14,848 attempt 1/10 2017-03-31 04:30:14,848 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/03d602fd723a?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-03-31 04:30:16,175 unpacking tar archive at: mozilla-central-03d602fd723a/testing/mozharness/ program finished with exit code 0 elapsedTime=1.677285 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-03-31 04:30:15.894385) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.894884) ========= script_repo_revision: 03d602fd723ad6ff4588c04855884ffa1dee9410 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.895410) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.895826) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-03-31 04:30:15.906008) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-31 04:30:15.906276) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-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', 'Firefox-Non-PGO', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox-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=0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 04:30:16 INFO - MultiFileLogger online at 20170331 04:30:16 in /builds/slave/test 04:30:16 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs-e10s --add-option --webServer,localhost --branch-name Firefox-Non-PGO --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox-Non-PGO 04:30:16 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 04:30:16 INFO - {'append_to_log': False, 04:30:16 INFO - 'base_work_dir': '/builds/slave/test', 04:30:16 INFO - 'blob_upload_branch': 'Firefox-Non-PGO', 04:30:16 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 04:30:16 INFO - 'branch': 'Firefox-Non-PGO', 04:30:16 INFO - 'buildbot_json_path': 'buildprops.json', 04:30:16 INFO - 'config_files': ('talos/linux_config.py',), 04:30:16 INFO - 'default_actions': ('clobber', 04:30:16 INFO - 'read-buildbot-config', 04:30:16 INFO - 'download-and-extract', 04:30:16 INFO - 'populate-webroot', 04:30:16 INFO - 'create-virtualenv', 04:30:16 INFO - 'install', 04:30:16 INFO - 'run-tests'), 04:30:16 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 04:30:16 INFO - 'download_minidump_stackwalk': True, 04:30:16 INFO - 'download_symbols': 'ondemand', 04:30:16 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 04:30:16 INFO - 'tooltool.py': '/tools/tooltool.py', 04:30:16 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 04:30:16 INFO - '/tools/misc-python/virtualenv.py')}, 04:30:16 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 04:30:16 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 04:30:16 INFO - 'gecko_profile': False, 04:30:16 INFO - 'gecko_profile_interval': 0, 04:30:16 INFO - 'installer_path': 'installer.exe', 04:30:16 INFO - 'log_level': 'info', 04:30:16 INFO - 'log_name': 'talos', 04:30:16 INFO - 'log_to_console': True, 04:30:16 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 04:30:16 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 04:30:16 INFO - 'opt_config_files': (), 04:30:16 INFO - 'pip_index': False, 04:30:16 INFO - 'suite': 'dromaeojs-e10s', 04:30:16 INFO - 'system_bits': '32', 04:30:16 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 04:30:16 INFO - 'title': 'talos-linux64-ix-073', 04:30:16 INFO - 'tooltool_cache': '/builds/tooltool_cache', 04:30:16 INFO - 'use_talos_json': True, 04:30:16 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 04:30:16 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 04:30:16 INFO - 'work_dir': 'build'} 04:30:16 INFO - [mozharness: 2017-03-31 11:30:16.653485Z] Running clobber step. 04:30:16 INFO - Running pre-action listener: _resource_record_pre_action 04:30:16 INFO - Running main action method: clobber 04:30:16 INFO - rmtree: /builds/slave/test/build 04:30:16 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 04:30:17 INFO - Running post-action listener: _resource_record_post_action 04:30:17 INFO - [mozharness: 2017-03-31 11:30:17.622021Z] Finished clobber step (success) 04:30:17 INFO - [mozharness: 2017-03-31 11:30:17.622119Z] Running read-buildbot-config step. 04:30:17 INFO - Running pre-action listener: _resource_record_pre_action 04:30:17 INFO - Running main action method: read_buildbot_config 04:30:17 INFO - Using buildbot properties: 04:30:17 INFO - { 04:30:17 INFO - "project": "", 04:30:17 INFO - "product": "firefox", 04:30:17 INFO - "who": "cbook@mozilla.com", 04:30:17 INFO - "installer_path": "public/build/target.tar.bz2", 04:30:17 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs-e10s", 04:30:17 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 04:30:17 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central talos dromaeojs-e10s", 04:30:17 INFO - "stage_platform": "linux64", 04:30:17 INFO - "basedir": "/builds/slave/test", 04:30:17 INFO - "buildnumber": 746, 04:30:17 INFO - "platform": "ubuntu64_hw", 04:30:17 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 04:30:17 INFO - "slavebuilddir": "test", 04:30:17 INFO - "taskId": "UFxz5J0yRrKlEYJovTfBOQ", 04:30:17 INFO - "branch": "mozilla-central", 04:30:17 INFO - "script_repo_revision": "production", 04:30:17 INFO - "revision": "03d602fd723ad6ff4588c04855884ffa1dee9410", 04:30:17 INFO - "slavename": "talos-linux64-ix-073", 04:30:17 INFO - "repo_path": "mozilla-central" 04:30:17 INFO - } 04:30:17 INFO - Finding installer, test and symbols from parent task. 04:30:17 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UFxz5J0yRrKlEYJovTfBOQ'}, attempt #1 04:30:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/UFxz5J0yRrKlEYJovTfBOQ'}, attempt #1 04:30:18 INFO - Task dependencies: FziPe46tTL-TfFHS0Z7sXw 04:30:18 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw'}, attempt #1 04:30:19 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:19 INFO - Set test_url: https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/test_packages.json 04:30:19 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip 04:30:19 INFO - Running post-action listener: _resource_record_post_action 04:30:19 INFO - [mozharness: 2017-03-31 11:30:19.007542Z] Finished read-buildbot-config step (success) 04:30:19 INFO - [mozharness: 2017-03-31 11:30:19.007757Z] Running download-and-extract step. 04:30:19 INFO - Running pre-action listener: _resource_record_pre_action 04:30:19 INFO - Running main action method: download_and_extract 04:30:19 INFO - mkdir: /builds/slave/test/build/tests 04:30:19 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')]} 04:30:19 INFO - trying https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.test_packages.json 04:30:19 INFO - Downloading https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 04:30:19 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 04:30:19 INFO - Downloaded 986 bytes. 04:30:19 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 04:30:19 INFO - Using the following test package requirements: 04:30:19 INFO - {u'awsy': [u'target.common.tests.zip', u'target.awsy.tests.zip'], 04:30:19 INFO - u'common': [u'target.common.tests.zip'], 04:30:19 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 04:30:19 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 04:30:19 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 04:30:19 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 04:30:19 INFO - u'mozbase': [u'target.common.tests.zip'], 04:30:19 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 04:30:19 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 04:30:19 INFO - u'web-platform': [u'target.common.tests.zip', 04:30:19 INFO - u'target.web-platform.tests.zip'], 04:30:19 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 04:30:19 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 04:30:19 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip 04:30:19 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:30:19 INFO - Fetch https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip into memory 04:30:20 INFO - Expected file size: 58227794 04:30:20 INFO - Obtained file size: 58227794 04:30:30 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 04:30:30 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip 04:30:30 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip'}, attempt #1 04:30:30 INFO - Fetch https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.common.tests.zip into memory 04:30:31 INFO - Expected file size: 58227794 04:30:31 INFO - Obtained file size: 58227794 04:30:42 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.talos.tests.zip 04:30:42 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 04:30:42 INFO - Fetch https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.talos.tests.zip into memory 04:30:43 INFO - Expected file size: 14404152 04:30:43 INFO - Obtained file size: 14404152 04:30:43 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:43 INFO - trying https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:43 INFO - Downloading https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 04:30:43 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 04:30:44 INFO - Downloaded 62478421 bytes. 04:30:44 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:44 INFO - mkdir: /builds/slave/test/properties 04:30:44 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 04:30:44 INFO - Writing to file /builds/slave/test/properties/build_url 04:30:44 INFO - Contents: 04:30:44 INFO - build_url:https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 04:30:44 INFO - Running post-action listener: _resource_record_post_action 04:30:44 INFO - Running post-action listener: set_extra_try_arguments 04:30:44 INFO - [mozharness: 2017-03-31 11:30:44.516944Z] Finished download-and-extract step (success) 04:30:44 INFO - [mozharness: 2017-03-31 11:30:44.517039Z] Running populate-webroot step. 04:30:44 INFO - Running pre-action listener: _resource_record_pre_action 04:30:44 INFO - Running main action method: populate_webroot 04:30:44 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 04:30:44 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 04:30:44 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 04:30:44 INFO - '--tppagecycles', 04:30:44 INFO - '7'], 04:30:44 INFO - 'tests': ['tsvgm']}}, 04:30:44 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['tresize', 'tcanvasmark']}, 04:30:44 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 04:30:44 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['dromaeo_css', 'kraken']}, 04:30:44 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 04:30:44 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:44 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 04:30:44 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['damp', 'tps']}, 04:30:44 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'tests': ['damp', 'tps']}, 04:30:44 INFO - 'g3': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['dromaeo_dom']}, 04:30:44 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 04:30:44 INFO - 'g4': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['basic_compositor_video']}, 04:30:44 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 04:30:44 INFO - 'other': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['a11yr', 04:30:44 INFO - 'ts_paint', 04:30:44 INFO - 'tpaint', 04:30:44 INFO - 'sessionrestore', 04:30:44 INFO - 'sessionrestore_no_auto_restore', 04:30:44 INFO - 'tabpaint']}, 04:30:44 INFO - 'other-e10s': {'tests': ['a11yr', 04:30:44 INFO - 'ts_paint', 04:30:44 INFO - 'tpaint', 04:30:44 INFO - 'sessionrestore', 04:30:44 INFO - 'sessionrestore_no_auto_restore', 04:30:44 INFO - 'tabpaint']}, 04:30:44 INFO - 'other-e10s_l64': {'tests': ['a11yr', 04:30:44 INFO - 'ts_paint', 04:30:44 INFO - 'tpaint', 04:30:44 INFO - 'sessionrestore', 04:30:44 INFO - 'sessionrestore_no_auto_restore', 04:30:44 INFO - 'tabpaint']}, 04:30:44 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 04:30:44 INFO - 'ts_paint', 04:30:44 INFO - 'tpaint', 04:30:44 INFO - 'sessionrestore', 04:30:44 INFO - 'sessionrestore_no_auto_restore', 04:30:44 INFO - 'tabpaint']}, 04:30:44 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['a11yr', 04:30:44 INFO - 'ts_paint', 04:30:44 INFO - 'tpaint', 04:30:44 INFO - 'sessionrestore', 04:30:44 INFO - 'sessionrestore_no_auto_restore', 04:30:44 INFO - 'tabpaint']}, 04:30:44 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['a11yr', 04:30:44 INFO - 'ts_paint', 04:30:44 INFO - 'tpaint', 04:30:44 INFO - 'sessionrestore', 04:30:44 INFO - 'sessionrestore_no_auto_restore', 04:30:44 INFO - 'tabpaint']}, 04:30:44 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['tsvgx', 04:30:44 INFO - 'tsvgr_opacity', 04:30:44 INFO - 'tart', 04:30:44 INFO - 'tscrollx', 04:30:44 INFO - 'cart', 04:30:44 INFO - 'tsvg_static']}, 04:30:44 INFO - 'svgr-e10s': {'tests': ['tsvgx', 04:30:44 INFO - 'tsvgr_opacity', 04:30:44 INFO - 'tart', 04:30:44 INFO - 'tscrollx', 04:30:44 INFO - 'cart', 04:30:44 INFO - 'tsvg_static']}, 04:30:44 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'talos_options': ['--disable-e10s'], 04:30:44 INFO - 'tests': ['tp5o']}, 04:30:44 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 04:30:44 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'talos_options': ['--disable-e10s', 04:30:44 INFO - '--xperf_path', 04:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:44 INFO - 'tests': ['tp5n']}, 04:30:44 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 04:30:44 INFO - 'talos_options': ['--xperf_path', 04:30:44 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 04:30:44 INFO - 'tests': ['tp5n']}}, 04:30:44 INFO - 'talos.zip': {'path': '', 04:30:44 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 04:30:44 INFO - Running post-action listener: _resource_record_post_action 04:30:44 INFO - [mozharness: 2017-03-31 11:30:44.530099Z] Finished populate-webroot step (success) 04:30:44 INFO - [mozharness: 2017-03-31 11:30:44.530188Z] Running create-virtualenv step. 04:30:44 INFO - Running pre-action listener: _resource_record_pre_action 04:30:44 INFO - Running main action method: create_virtualenv 04:30:44 INFO - Creating virtualenv /builds/slave/test/build/venv 04:30:44 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 04:30:44 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 04:30:44 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 04:30:44 INFO - Using real prefix '/usr' 04:30:44 INFO - New python executable in /builds/slave/test/build/venv/bin/python 04:30:45 INFO - Installing distribute.............................................................................................................................................................................................done. 04:30:47 INFO - Installing pip.................done. 04:30:47 INFO - Return code: 0 04:30:47 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 04:30:47 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 04:30:47 INFO - Reading from file tmpfile_stdout 04:30:47 INFO - Output received: 04:30:47 INFO - pip 1.5.5 from /builds/slave/test/build/venv/local/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg (python 2.7) 04:30:47 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 04:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:47 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:47 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 04:30:47 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 04:30:47 INFO - Using env: {'DISPLAY': ':0', 04:30:47 INFO - 'HOME': '/home/cltbld', 04:30:47 INFO - 'LANG': 'en_US.UTF-8', 04:30:47 INFO - 'LANGUAGE': 'en_US:en', 04:30:47 INFO - 'LOGNAME': 'cltbld', 04:30:47 INFO - 'MAIL': '/var/mail/cltbld', 04:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:47 INFO - 'MOZ_NO_REMOTE': '1', 04:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:47 INFO - 'NO_EM_RESTART': '1', 04:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:47 INFO - 'PWD': '/builds/slave/test', 04:30:47 INFO - 'SHELL': '/bin/bash', 04:30:47 INFO - 'SHLVL': '1', 04:30:47 INFO - 'TERM': 'linux', 04:30:47 INFO - 'TMOUT': '86400', 04:30:47 INFO - 'USER': 'cltbld', 04:30:47 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:47 INFO - '_': '/tools/buildbot/bin/python'} 04:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:47 INFO - Requirement already satisfied (use --upgrade to upgrade): pip>=1.5 in ./venv/lib/python2.7/site-packages/pip-1.5.5-py2.7.egg 04:30:47 INFO - Cleaning up... 04:30:47 INFO - Return code: 0 04:30:47 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 04:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:47 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:47 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:47 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:47 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:47 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:47 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 04:30:47 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 04:30:47 INFO - Using env: {'DISPLAY': ':0', 04:30:47 INFO - 'HOME': '/home/cltbld', 04:30:47 INFO - 'LANG': 'en_US.UTF-8', 04:30:47 INFO - 'LANGUAGE': 'en_US:en', 04:30:47 INFO - 'LOGNAME': 'cltbld', 04:30:47 INFO - 'MAIL': '/var/mail/cltbld', 04:30:47 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:47 INFO - 'MOZ_NO_REMOTE': '1', 04:30:47 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:47 INFO - 'NO_EM_RESTART': '1', 04:30:47 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:47 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:47 INFO - 'PWD': '/builds/slave/test', 04:30:47 INFO - 'SHELL': '/bin/bash', 04:30:47 INFO - 'SHLVL': '1', 04:30:47 INFO - 'TERM': 'linux', 04:30:47 INFO - 'TMOUT': '86400', 04:30:47 INFO - 'USER': 'cltbld', 04:30:47 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:47 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:47 INFO - '_': '/tools/buildbot/bin/python'} 04:30:47 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:47 INFO - Downloading/unpacking psutil>=3.1.1 04:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:47 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:47 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:47 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:47 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:49 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 04:30:49 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:49 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:49 INFO - Installing collected packages: psutil 04:30:49 INFO - Running setup.py install for psutil 04:30:49 INFO - building 'psutil._psutil_linux' extension 04:30:49 INFO - gcc -pthread -fno-strict-aliasing -DNDEBUG -g -fwrapv -O2 -Wall -Wstrict-prototypes -fPIC -DPSUTIL_VERSION=311 -I/usr/include/python2.7 -c psutil/_psutil_linux.c -o build/temp.linux-x86_64-2.7/psutil/_psutil_linux.o 04:30:50 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 04:30:50 INFO - building 'psutil._psutil_posix' extension 04:30:50 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 04:30:50 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 04:30:50 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 04:30:50 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 04:30:50 INFO - Successfully installed psutil 04:30:50 INFO - Cleaning up... 04:30:50 INFO - Return code: 0 04:30:50 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 04:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:50 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:50 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:50 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:50 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:50 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3']], kwargs: {'error_level': 'warning', 'error_list': [{'substr': 'not found or a compiler error:', 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:50 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'mozsystemmonitor==0.3'] in /builds/slave/test/build 04:30:50 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub mozsystemmonitor==0.3 04:30:50 INFO - Using env: {'DISPLAY': ':0', 04:30:50 INFO - 'HOME': '/home/cltbld', 04:30:50 INFO - 'LANG': 'en_US.UTF-8', 04:30:50 INFO - 'LANGUAGE': 'en_US:en', 04:30:50 INFO - 'LOGNAME': 'cltbld', 04:30:50 INFO - 'MAIL': '/var/mail/cltbld', 04:30:50 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:50 INFO - 'MOZ_NO_REMOTE': '1', 04:30:50 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:50 INFO - 'NO_EM_RESTART': '1', 04:30:50 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:50 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:50 INFO - 'PWD': '/builds/slave/test', 04:30:50 INFO - 'SHELL': '/bin/bash', 04:30:50 INFO - 'SHLVL': '1', 04:30:50 INFO - 'TERM': 'linux', 04:30:50 INFO - 'TMOUT': '86400', 04:30:50 INFO - 'USER': 'cltbld', 04:30:50 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:50 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:50 INFO - '_': '/tools/buildbot/bin/python'} 04:30:50 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:50 INFO - Downloading/unpacking mozsystemmonitor==0.3 04:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:50 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:50 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:50 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:50 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:52 INFO - Downloading mozsystemmonitor-0.3.tar.gz 04:30:52 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 04:30:52 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 04:30:52 INFO - Installing collected packages: mozsystemmonitor 04:30:52 INFO - Running setup.py install for mozsystemmonitor 04:30:52 INFO - Successfully installed mozsystemmonitor 04:30:52 INFO - Cleaning up... 04:30:52 INFO - Return code: 0 04:30:52 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 04:30: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')]} 04:30:52 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:52 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30: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')]} 04:30:52 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:52 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:52 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:52 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 04:30:52 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 04:30:52 INFO - Using env: {'DISPLAY': ':0', 04:30:52 INFO - 'HOME': '/home/cltbld', 04:30:52 INFO - 'LANG': 'en_US.UTF-8', 04:30:52 INFO - 'LANGUAGE': 'en_US:en', 04:30:52 INFO - 'LOGNAME': 'cltbld', 04:30:52 INFO - 'MAIL': '/var/mail/cltbld', 04:30:52 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:52 INFO - 'MOZ_NO_REMOTE': '1', 04:30:52 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:52 INFO - 'NO_EM_RESTART': '1', 04:30:52 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:52 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:52 INFO - 'PWD': '/builds/slave/test', 04:30:52 INFO - 'SHELL': '/bin/bash', 04:30:52 INFO - 'SHLVL': '1', 04:30:52 INFO - 'TERM': 'linux', 04:30:52 INFO - 'TMOUT': '86400', 04:30:52 INFO - 'USER': 'cltbld', 04:30:52 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:52 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:52 INFO - '_': '/tools/buildbot/bin/python'} 04:30:53 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:53 INFO - Downloading/unpacking jsonschema==2.5.1 04:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:53 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:53 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:53 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:53 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:55 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 04:30:55 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 04:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:55 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:55 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:55 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:55 INFO - Downloading functools32-3.2.3-2.tar.gz 04:30:55 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 04:30:55 INFO - warning: no files found matching '*.txt' 04:30:55 INFO - no previously-included directories found matching 'build' 04:30:55 INFO - no previously-included directories found matching 'dist' 04:30:55 INFO - no previously-included directories found matching '.git*' 04:30:55 INFO - Installing collected packages: jsonschema, functools32 04:30:55 INFO - Running setup.py install for functools32 04:30:55 INFO - warning: no files found matching '*.txt' 04:30:55 INFO - no previously-included directories found matching 'build' 04:30:55 INFO - no previously-included directories found matching 'dist' 04:30:55 INFO - no previously-included directories found matching '.git*' 04:30:55 INFO - Successfully installed jsonschema functools32 04:30:55 INFO - Cleaning up... 04:30:55 INFO - Return code: 0 04:30:55 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 04:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'functools32==3.2.3-2'] in /builds/slave/test/build 04:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub functools32==3.2.3-2 04:30:55 INFO - Using env: {'DISPLAY': ':0', 04:30:55 INFO - 'HOME': '/home/cltbld', 04:30:55 INFO - 'LANG': 'en_US.UTF-8', 04:30:55 INFO - 'LANGUAGE': 'en_US:en', 04:30:55 INFO - 'LOGNAME': 'cltbld', 04:30:55 INFO - 'MAIL': '/var/mail/cltbld', 04:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:55 INFO - 'MOZ_NO_REMOTE': '1', 04:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:55 INFO - 'NO_EM_RESTART': '1', 04:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:55 INFO - 'PWD': '/builds/slave/test', 04:30:55 INFO - 'SHELL': '/bin/bash', 04:30:55 INFO - 'SHLVL': '1', 04:30:55 INFO - 'TERM': 'linux', 04:30:55 INFO - 'TMOUT': '86400', 04:30:55 INFO - 'USER': 'cltbld', 04:30:55 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:55 INFO - '_': '/tools/buildbot/bin/python'} 04:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:55 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 04:30:55 INFO - Cleaning up... 04:30:55 INFO - Return code: 0 04:30:55 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 04:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:55 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:55 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:55 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:55 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:55 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:55 INFO - retry: Calling run_command with args: [['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', '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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:55 INFO - Running command: ['/builds/slave/test/build/venv/bin/pip', 'install', '--timeout', '120', '--no-index', '--find-links', 'http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub', '--find-links', 'http://pypi.pvt.build.mozilla.org/pub', '--find-links', 'http://pypi.pub.build.mozilla.org/pub', 'blobuploader==1.2.4'] in /builds/slave/test/build 04:30:55 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip install --timeout 120 --no-index --find-links http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub --find-links http://pypi.pvt.build.mozilla.org/pub --find-links http://pypi.pub.build.mozilla.org/pub blobuploader==1.2.4 04:30:55 INFO - Using env: {'DISPLAY': ':0', 04:30:55 INFO - 'HOME': '/home/cltbld', 04:30:55 INFO - 'LANG': 'en_US.UTF-8', 04:30:55 INFO - 'LANGUAGE': 'en_US:en', 04:30:55 INFO - 'LOGNAME': 'cltbld', 04:30:55 INFO - 'MAIL': '/var/mail/cltbld', 04:30:55 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:55 INFO - 'MOZ_NO_REMOTE': '1', 04:30:55 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:55 INFO - 'NO_EM_RESTART': '1', 04:30:55 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:55 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:55 INFO - 'PWD': '/builds/slave/test', 04:30:55 INFO - 'SHELL': '/bin/bash', 04:30:55 INFO - 'SHLVL': '1', 04:30:55 INFO - 'TERM': 'linux', 04:30:55 INFO - 'TMOUT': '86400', 04:30:55 INFO - 'USER': 'cltbld', 04:30:55 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:55 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:55 INFO - '_': '/tools/buildbot/bin/python'} 04:30:55 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:56 INFO - Downloading/unpacking blobuploader==1.2.4 04:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:56 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:56 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:56 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:56 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:58 INFO - Downloading blobuploader-1.2.4.tar.gz 04:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 04:30:58 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 04:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 04:30:58 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 04:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:58 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:58 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:30:58 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:30:58 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:30:58 INFO - Downloading docopt-0.6.1.tar.gz 04:30:58 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 04:30:58 INFO - Installing collected packages: blobuploader, requests, docopt 04:30:58 INFO - Running setup.py install for blobuploader 04:30:58 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 04:30:58 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 04:30:58 INFO - Running setup.py install for requests 04:30:59 INFO - Running setup.py install for docopt 04:30:59 INFO - Successfully installed blobuploader requests docopt 04:30:59 INFO - Cleaning up... 04:30:59 INFO - Return code: 0 04:30:59 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:59 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:30:59 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:59 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:30:59 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:30:59 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:30:59 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:30:59 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 04:30:59 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 04:30:59 INFO - Using env: {'DISPLAY': ':0', 04:30:59 INFO - 'HOME': '/home/cltbld', 04:30:59 INFO - 'LANG': 'en_US.UTF-8', 04:30:59 INFO - 'LANGUAGE': 'en_US:en', 04:30:59 INFO - 'LOGNAME': 'cltbld', 04:30:59 INFO - 'MAIL': '/var/mail/cltbld', 04:30:59 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:30:59 INFO - 'MOZ_NO_REMOTE': '1', 04:30:59 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:30:59 INFO - 'NO_EM_RESTART': '1', 04:30:59 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:30:59 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:30:59 INFO - 'PWD': '/builds/slave/test', 04:30:59 INFO - 'SHELL': '/bin/bash', 04:30:59 INFO - 'SHLVL': '1', 04:30:59 INFO - 'TERM': 'linux', 04:30:59 INFO - 'TMOUT': '86400', 04:30:59 INFO - 'USER': 'cltbld', 04:30:59 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:30:59 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:30:59 INFO - '_': '/tools/buildbot/bin/python'} 04:30:59 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:30:59 INFO - Running setup.py (path:/tmp/pip-gqDn1Q-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:30:59 INFO - Running setup.py (path:/tmp/pip-uSoKU4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:30:59 INFO - Running setup.py (path:/tmp/pip-StAWbW-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:30:59 INFO - Running setup.py (path:/tmp/pip-eY9ErV-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:30:59 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:30:59 INFO - Running setup.py (path:/tmp/pip-n5Y6qH-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:00 INFO - Running setup.py (path:/tmp/pip-JxF1FS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:00 INFO - Running setup.py (path:/tmp/pip-DGgmUs-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:00 INFO - Running setup.py (path:/tmp/pip-kGX1Jd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:00 INFO - Running setup.py (path:/tmp/pip-Krsrob-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:00 INFO - Running setup.py (path:/tmp/pip-kypIkd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:00 INFO - Running setup.py (path:/tmp/pip-WcBTK6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:00 INFO - Running setup.py (path:/tmp/pip-Wa8MFD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:00 INFO - Running setup.py (path:/tmp/pip-jmCH60-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:00 INFO - Running setup.py (path:/tmp/pip-_zK3UB-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:00 INFO - Running setup.py (path:/tmp/pip-Q1zLzS-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:00 INFO - Running setup.py (path:/tmp/pip-rgxZN3-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:00 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:00 INFO - Running setup.py (path:/tmp/pip-WcNLIQ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:00 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 04:31:00 INFO - Running setup.py install for manifestparser 04:31:00 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 04:31:00 INFO - Running setup.py install for mozcrash 04:31:01 INFO - Running setup.py install for mozdebug 04:31:01 INFO - Running setup.py install for mozdevice 04:31:01 INFO - Installing dm script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for mozfile 04:31:01 INFO - Running setup.py install for mozhttpd 04:31:01 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for mozinfo 04:31:01 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for mozInstall 04:31:01 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for mozleak 04:31:01 INFO - Running setup.py install for mozlog 04:31:01 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 04:31:01 INFO - Running setup.py install for moznetwork 04:31:01 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Running setup.py install for mozprocess 04:31:02 INFO - Running setup.py install for mozprofile 04:31:02 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Running setup.py install for mozrunner 04:31:02 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Running setup.py install for mozscreenshot 04:31:02 INFO - Running setup.py install for moztest 04:31:02 INFO - Running setup.py install for mozversion 04:31:02 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 04:31:02 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 04:31:02 INFO - Cleaning up... 04:31:02 INFO - Return code: 0 04:31:02 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:02 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:02 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:02 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:02 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:02 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:02 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:31:02 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 04:31:02 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 04:31:02 INFO - Using env: {'DISPLAY': ':0', 04:31:02 INFO - 'HOME': '/home/cltbld', 04:31:02 INFO - 'LANG': 'en_US.UTF-8', 04:31:02 INFO - 'LANGUAGE': 'en_US:en', 04:31:02 INFO - 'LOGNAME': 'cltbld', 04:31:02 INFO - 'MAIL': '/var/mail/cltbld', 04:31:02 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:02 INFO - 'MOZ_NO_REMOTE': '1', 04:31:02 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:02 INFO - 'NO_EM_RESTART': '1', 04:31:02 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:02 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:02 INFO - 'PWD': '/builds/slave/test', 04:31:02 INFO - 'SHELL': '/bin/bash', 04:31:02 INFO - 'SHLVL': '1', 04:31:02 INFO - 'TERM': 'linux', 04:31:02 INFO - 'TMOUT': '86400', 04:31:02 INFO - 'USER': 'cltbld', 04:31:02 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:31:02 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:02 INFO - '_': '/tools/buildbot/bin/python'} 04:31:02 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 04:31:02 INFO - Running setup.py (path:/tmp/pip-qBQv26-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 04:31:02 INFO - Requirement already satisfied (use --upgrade to upgrade): manifestparser==1.1 from file:///builds/slave/test/build/tests/mozbase/manifestparser in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 1)) 04:31:02 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 04:31:02 INFO - Running setup.py (path:/tmp/pip-NAw8iD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash==1.0 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)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 04:31:03 INFO - Running setup.py (path:/tmp/pip-jhkUTX-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdebug==0.1 from file:///builds/slave/test/build/tests/mozbase/mozdebug in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 04:31:03 INFO - Running setup.py (path:/tmp/pip-yfV469-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozdevice==0.49 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)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 04:31:03 INFO - Running setup.py (path:/tmp/pip-5wL4Wg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile==1.2 from file:///builds/slave/test/build/tests/mozbase/mozfile in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 5)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 04:31:03 INFO - Running setup.py (path:/tmp/pip-6fAav1-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd==0.7 from file:///builds/slave/test/build/tests/mozbase/mozhttpd in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 6)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 04:31:03 INFO - Running setup.py (path:/tmp/pip-MsC_E6-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo==0.9 from file:///builds/slave/test/build/tests/mozbase/mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 7)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 04:31:03 INFO - Running setup.py (path:/tmp/pip-or2swz-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozInstall==1.12 from file:///builds/slave/test/build/tests/mozbase/mozinstall in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 8)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 04:31:03 INFO - Running setup.py (path:/tmp/pip-1KaKA7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozleak==0.1 from file:///builds/slave/test/build/tests/mozbase/mozleak in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 9)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 04:31:03 INFO - Running setup.py (path:/tmp/pip-Fvj3QM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 04:31:03 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)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 04:31:03 INFO - Running setup.py (path:/tmp/pip-rOd0c9-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork==0.27 from file:///builds/slave/test/build/tests/mozbase/moznetwork in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 11)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 04:31:03 INFO - Running setup.py (path:/tmp/pip-nmrxPU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess==0.25 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)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 04:31:03 INFO - Running setup.py (path:/tmp/pip-BSeXRM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 04:31:03 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)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 04:31:03 INFO - Running setup.py (path:/tmp/pip-5Gs6sR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozrunner==6.13 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)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:03 INFO - Running setup.py (path:/tmp/pip-61RS9Y-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 04:31:03 INFO - Requirement already satisfied (use --upgrade to upgrade): mozscreenshot==0.1 from file:///builds/slave/test/build/tests/mozbase/mozscreenshot in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 15)) 04:31:03 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 04:31:03 INFO - Running setup.py (path:/tmp/pip-aU8rTU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moztest==0.8 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)) 04:31:04 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 04:31:04 INFO - Running setup.py (path:/tmp/pip-G5QAMK-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion==1.4 from file:///builds/slave/test/build/tests/mozbase/mozversion in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 17)) 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozlog>=3.0 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozcrash==1.0->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 2)) 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdebug==0.1->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 3)) 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:04 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.19 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozdevice==0.49->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 4)) 04:31:04 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 04:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:04 INFO - http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:04 INFO - http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com has it available 04:31:04 INFO - http://pypi.pvt.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pvt.build.mozilla.org has it available 04:31:04 INFO - http://pypi.pub.build.mozilla.org/pub uses an insecure transport scheme (http). Consider using https if pypi.pub.build.mozilla.org has it available 04:31:06 INFO - Downloading blessings-1.6.tar.gz 04:31:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 04:31:06 INFO - Installing collected packages: blessings 04:31:06 INFO - Running setup.py install for blessings 04:31:06 INFO - Successfully installed blessings 04:31:06 INFO - Cleaning up... 04:31:06 INFO - Return code: 0 04:31:06 INFO - Done creating virtualenv /builds/slave/test/build/venv. 04:31:06 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:06 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:06 INFO - Reading from file tmpfile_stdout 04:31:06 INFO - Current package versions: 04:31:06 INFO - argparse == 1.2.1 04:31:06 INFO - blessings == 1.6 04:31:06 INFO - blobuploader == 1.2.4 04:31:06 INFO - docopt == 0.6.1 04:31:06 INFO - functools32 == 3.2.3-2 04:31:06 INFO - jsonschema == 2.5.1 04:31:06 INFO - manifestparser == 1.1 04:31:06 INFO - mozInstall == 1.12 04:31:06 INFO - mozcrash == 1.0 04:31:06 INFO - mozdebug == 0.1 04:31:06 INFO - mozdevice == 0.49 04:31:06 INFO - mozfile == 1.2 04:31:06 INFO - mozhttpd == 0.7 04:31:06 INFO - mozinfo == 0.9 04:31:06 INFO - mozleak == 0.1 04:31:06 INFO - mozlog == 3.4 04:31:06 INFO - moznetwork == 0.27 04:31:06 INFO - mozprocess == 0.25 04:31:06 INFO - mozprofile == 0.28 04:31:06 INFO - mozrunner == 6.13 04:31:06 INFO - mozscreenshot == 0.1 04:31:06 INFO - mozsystemmonitor == 0.3 04:31:06 INFO - moztest == 0.8 04:31:06 INFO - mozversion == 1.4 04:31:06 INFO - psutil == 3.1.1 04:31:06 INFO - requests == 1.2.3 04:31:06 INFO - wsgiref == 0.1.2 04:31:06 INFO - Installing None into virtualenv /builds/slave/test/build/venv 04:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:06 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:31:06 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 04:31:06 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 04:31:06 INFO - Using env: {'DISPLAY': ':0', 04:31:06 INFO - 'HOME': '/home/cltbld', 04:31:06 INFO - 'LANG': 'en_US.UTF-8', 04:31:06 INFO - 'LANGUAGE': 'en_US:en', 04:31:06 INFO - 'LOGNAME': 'cltbld', 04:31:06 INFO - 'MAIL': '/var/mail/cltbld', 04:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:06 INFO - 'MOZ_NO_REMOTE': '1', 04:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:06 INFO - 'NO_EM_RESTART': '1', 04:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:06 INFO - 'PWD': '/builds/slave/test', 04:31:06 INFO - 'SHELL': '/bin/bash', 04:31:06 INFO - 'SHLVL': '1', 04:31:06 INFO - 'TERM': 'linux', 04:31:06 INFO - 'TMOUT': '86400', 04:31:06 INFO - 'USER': 'cltbld', 04:31:06 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:06 INFO - '_': '/tools/buildbot/bin/python'} 04:31:06 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:06 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)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozcrash>=0.15 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 2)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozfile>=1.2 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 3)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozhttpd>=0.7 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozinfo>=0.8 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 5)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprocess>=0.22 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 6)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozversion>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 7)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): mozprofile>=0.25 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 8)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from -r /builds/slave/test/build/tests/talos/requirements.txt (line 9)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): blessings>=1.3 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozlog>=3.1->-r /builds/slave/test/build/tests/talos/requirements.txt (line 1)) 04:31:06 INFO - Requirement already satisfied (use --upgrade to upgrade): moznetwork>=0.24 in /builds/slave/test/build/venv/lib/python2.7/site-packages (from mozhttpd>=0.7->-r /builds/slave/test/build/tests/talos/requirements.txt (line 4)) 04:31:06 INFO - Cleaning up... 04:31:06 INFO - Return code: 0 04:31:06 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 04:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:06 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 04:31:06 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:06 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:06 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 04:31:06 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 04:31:06 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 0x13491f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x1441a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x15e6b10>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, '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': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', '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 04:31:06 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 04:31:06 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 04:31:06 INFO - Using env: {'DISPLAY': ':0', 04:31:06 INFO - 'HOME': '/home/cltbld', 04:31:06 INFO - 'LANG': 'en_US.UTF-8', 04:31:06 INFO - 'LANGUAGE': 'en_US:en', 04:31:06 INFO - 'LOGNAME': 'cltbld', 04:31:06 INFO - 'MAIL': '/var/mail/cltbld', 04:31:06 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:06 INFO - 'MOZ_NO_REMOTE': '1', 04:31:06 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:06 INFO - 'NO_EM_RESTART': '1', 04:31:06 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:06 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:06 INFO - 'PWD': '/builds/slave/test', 04:31:06 INFO - 'SHELL': '/bin/bash', 04:31:06 INFO - 'SHLVL': '1', 04:31:06 INFO - 'TERM': 'linux', 04:31:06 INFO - 'TMOUT': '86400', 04:31:06 INFO - 'USER': 'cltbld', 04:31:06 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:31:06 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:06 INFO - '_': '/tools/buildbot/bin/python'} 04:31:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 04:31:07 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 04:31:07 INFO - Cleaning up... 04:31:07 INFO - Return code: 0 04:31:07 INFO - Running post-action listener: _resource_record_post_action 04:31:07 INFO - Running post-action listener: _start_resource_monitoring 04:31:07 INFO - Starting resource monitoring. 04:31:07 INFO - [mozharness: 2017-03-31 11:31:07.133999Z] Finished create-virtualenv step (success) 04:31:07 INFO - [mozharness: 2017-03-31 11:31:07.134476Z] Running install step. 04:31:07 INFO - Running pre-action listener: _resource_record_pre_action 04:31:07 INFO - Running main action method: install 04:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 04:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 04:31:07 INFO - Reading from file tmpfile_stdout 04:31:07 INFO - Detecting whether we're running mozinstall >=1.0... 04:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 04:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 04:31:07 INFO - Reading from file tmpfile_stdout 04:31:07 INFO - Output received: 04:31:07 INFO - Usage: mozinstall [options] installer 04:31:07 INFO - Options: 04:31:07 INFO - -h, --help show this help message and exit 04:31:07 INFO - -d DEST, --destination=DEST 04:31:07 INFO - Directory to install application into. [default: 04:31:07 INFO - "/builds/slave/test"] 04:31:07 INFO - --app=APP Application being installed. [default: firefox] 04:31:07 INFO - mkdir: /builds/slave/test/build/application 04:31:07 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '/builds/slave/test/build/target.tar.bz2', '--destination', '/builds/slave/test/build/application'] 04:31:07 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 04:31:23 INFO - Reading from file tmpfile_stdout 04:31:23 INFO - Output received: 04:31:23 INFO - /builds/slave/test/build/application/firefox/firefox 04:31:23 INFO - Running post-action listener: _resource_record_post_action 04:31:23 INFO - [mozharness: 2017-03-31 11:31:23.083534Z] Finished install step (success) 04:31:23 INFO - [mozharness: 2017-03-31 11:31:23.083807Z] Running run-tests step. 04:31:23 INFO - Running pre-action listener: _resource_record_pre_action 04:31:23 INFO - Running main action method: run_tests 04:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 04:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 04:31:23 INFO - Python 2.7.3 04:31:23 INFO - Return code: 0 04:31:23 INFO - grabbing minidump binary from tooltool 04:31:23 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 04:31:23 INFO - retry: Calling run_command with args: (['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'],), kwargs: {'output_timeout': 600, 'error_list': [{'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x15e4290>, '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 0x15e4b90>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x15e5040>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 04:31:23 INFO - Running command: ['/tools/tooltool.py', '--url', 'https://api.pub.build.mozilla.org/tooltool/', '--authentication-file', '/builds/relengapi.tok', 'fetch', '-m', '/builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest', '-o', '-c', '/builds/tooltool_cache'] in /builds/slave/test/build 04:31:23 INFO - Copy/paste: /tools/tooltool.py --url https://api.pub.build.mozilla.org/tooltool/ --authentication-file /builds/relengapi.tok fetch -m /builds/slave/test/build/tests/config/tooltool-manifests/linux64/releng.manifest -o -c /builds/tooltool_cache 04:31:23 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 04:31:23 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 04:31:23 INFO - Return code: 0 04:31:23 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 04:31:23 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 04:31:23 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 04:31:23 INFO - ENV: RUST_BACKTRACE is now 1 04:31:23 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 04:31:23 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 04:31:23 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 04:31:23 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox-Non-PGO --suite dromaeojs-e10s --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-073 --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 04:31:23 INFO - Using env: {'DISPLAY': ':0', 04:31:23 INFO - 'HOME': '/home/cltbld', 04:31:23 INFO - 'LANG': 'en_US.UTF-8', 04:31:23 INFO - 'LANGUAGE': 'en_US:en', 04:31:23 INFO - 'LOGNAME': 'cltbld', 04:31:23 INFO - 'MAIL': '/var/mail/cltbld', 04:31:23 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 04:31:23 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 04:31:23 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 04:31:23 INFO - 'MOZ_NO_REMOTE': '1', 04:31:23 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 04:31:23 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 04:31:23 INFO - 'NO_EM_RESTART': '1', 04:31:23 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 04:31:23 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 04:31:23 INFO - 'PWD': '/builds/slave/test', 04:31:23 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 04:31:23 INFO - 'RUST_BACKTRACE': '1', 04:31:23 INFO - 'SHELL': '/bin/bash', 04:31:23 INFO - 'SHLVL': '1', 04:31:23 INFO - 'TERM': 'linux', 04:31:23 INFO - 'TMOUT': '86400', 04:31:23 INFO - 'USER': 'cltbld', 04:31:23 INFO - 'XDG_SESSION_COOKIE': '0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725', 04:31:23 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 04:31:23 INFO - '_': '/tools/buildbot/bin/python'} 04:31:23 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox-Non-PGO', '--suite', 'dromaeojs-e10s', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-073', '--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 04:31:23 INFO - mozversion application_buildid: 20170330114804 04:31:23 INFO - mozversion application_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 04:31:23 INFO - mozversion application_display_name: Nightly 04:31:23 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 04:31:23 INFO - mozversion application_name: Firefox 04:31:23 INFO - mozversion application_remotingname: firefox 04:31:23 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 04:31:23 INFO - mozversion application_vendor: Mozilla 04:31:23 INFO - mozversion application_version: 55.0a1 04:31:23 INFO - mozversion platform_buildid: 20170330114804 04:31:23 INFO - mozversion platform_changeset: 03d602fd723ad6ff4588c04855884ffa1dee9410 04:31:23 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 04:31:23 INFO - mozversion platform_version: 55.0a1 04:31:23 INFO - using testdate: 1490959883 04:31:23 INFO - actual date: 1490959883 04:31:23 INFO - starting webserver on 'localhost:55892' 04:31:23 INFO - SUITE-START | Running 2 tests 04:31:23 INFO - TEST-START | dromaeo_css 04:31:23 INFO - operating with platform_type : linux_ 04:31:23 INFO - Initialising browser for dromaeo_css test... 04:31:23 INFO - TEST-INFO | started process 7746 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC0puI1/profile http://localhost:55892/getInfo.html) 04:31:27 INFO - PID 7746 | __metrics Screen width/height:1600/1200 04:31:27 INFO - PID 7746 | colorDepth:24 04:31:27 INFO - PID 7746 | Browser inner width/height: 1024/697 04:31:27 INFO - PID 7746 | __metrics 04:31:29 ERROR - PID 7746 | 1490959889041 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6382:36 < canClose@browser.js:5233:12 < forceQuit@jar:file:///tmp/tmpC0puI1/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpC0puI1/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpC0puI1/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpC0puI1/profile/extensions/talos-powers@TalosPowersService.js:55:9 04:31:29 ERROR - PID 7746 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:31:30 INFO - TEST-INFO | 7746: exit 0 04:31:30 INFO - Browser initialized. 04:31:30 INFO - Running cycle 1/1 for dromaeo_css test... 04:31:30 INFO - TEST-INFO | started process 7885 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpC0puI1/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:31:42 INFO - PID 7885 | RSS: Main: 155742208 04:31:42 INFO - PID 7885 | 04:34:47 INFO - PID 7885 | Cycle 1(1): loaded http://localhost:55892/tests/dromaeo/cssquery-dojo.html (next: http://localhost:55892/tests/dromaeo/cssquery-ext.html) 04:34:49 INFO - PID 7885 | RSS: Main: 166154240 04:34:49 INFO - PID 7885 | 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | A coding exception was thrown and uncaught in a Task. 04:36:30 INFO - PID 7885 | 04:36:30 ERROR - PID 7885 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:30 INFO - PID 7885 | Full stack: 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | A coding exception was thrown and uncaught in a Task. 04:36:30 INFO - PID 7885 | 04:36:30 ERROR - PID 7885 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:30 INFO - PID 7885 | Full stack: 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | A coding exception was thrown and uncaught in a Task. 04:36:30 INFO - PID 7885 | 04:36:30 ERROR - PID 7885 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:30 INFO - PID 7885 | Full stack: 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | ************************* 04:36:30 INFO - PID 7885 | A coding exception was thrown and uncaught in a Task. 04:36:30 INFO - PID 7885 | 04:36:30 ERROR - PID 7885 | Full message: TypeError: NetworkError when attempting to fetch resource. 04:36:30 INFO - PID 7885 | Full stack: 04:36:30 INFO - PID 7885 | ************************* 04:37:51 INFO - PID 7885 | Cycle 1(1): loaded http://localhost:55892/tests/dromaeo/cssquery-ext.html (next: http://localhost:55892/tests/dromaeo/cssquery-jquery.html) 04:37:51 INFO - PID 7885 | RSS: Main: 161378304 04:37:51 INFO - PID 7885 | 04:40:53 INFO - PID 7885 | Cycle 1(1): loaded http://localhost:55892/tests/dromaeo/cssquery-jquery.html (next: http://localhost:55892/tests/dromaeo/cssquery-mootools.html) 04:40:54 INFO - PID 7885 | RSS: Main: 169758720 04:40:54 INFO - PID 7885 | 04:43:56 INFO - PID 7885 | Cycle 1(1): loaded http://localhost:55892/tests/dromaeo/cssquery-mootools.html (next: http://localhost:55892/tests/dromaeo/cssquery-prototype.html) 04:43:57 INFO - PID 7885 | RSS: Main: 161189888 04:43:57 INFO - PID 7885 | 04:47:00 INFO - PID 7885 | Cycle 1(1): loaded http://localhost:55892/tests/dromaeo/cssquery-prototype.html (next: http://localhost:55892/tests/dromaeo/cssquery-yui.html) 04:47:00 INFO - PID 7885 | RSS: Main: 168423424 04:47:00 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | Cycle 1(1): loaded http://localhost:55892/tests/dromaeo/cssquery-yui.html (next: http://localhost:55892/tests/dromaeo/cssquery-dojo.html) 04:50:03 INFO - PID 7885 | RSS: Main: 162013184 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | __start_tp_report 04:50:03 INFO - PID 7885 | _x_x_mozilla_page_load 04:50:03 INFO - PID 7885 | _x_x_mozilla_page_load_details 04:50:03 INFO - PID 7885 | |i|pagename|runs| 04:50:03 INFO - PID 7885 | |0;dojo.html;2680.9463810723787;2708.377073273146;2715.2261605442445;2724.359775452768;2744.890204391824;5105.795768169269;5200.493768167879;5207.401148867882;5254.632175747694;5254.947450525494;5166.535011848933;5189.117849965505;5218.347706536683;5282.339707536558;5291.550218231455;4752.120857641339;4788.186008378584;4814.277858321245;4817.879553011167;4846.563809257166;4827.155247831633;4861.857463496093;4886.022795440908;4905.533974272438;4940.357753492041;4638.11875743611;4682.3210634458155;4686.320483529903;4821.951780482185;4826.903461930777;4710.599599034086;4731.219348807443;4767.046590681877;4809.975950120262;4821.276808478721;4646.883827904296;4675;4712.599429048535;4736.739479328636;4755.35802666639;5062.493750624908;5112.718800465973;5162.771970114961;5173.456787037345;5232.816851410183;5615.606907516435;5648.096304591287;5663.2637757091725;5679.977604031273;5682.801101961411;5679.261695979538;5682.488576028136;5683.488486036276;5684.204211410406;5704.942950570483;5177.456367081478;5232.110541208004;5266.21006848968;5281.603879708999;5327.493888080626;5653.575981801381;5662.943370566324;5739.16782066598;5801.477866991991;5834.241548598655;5382.138857782736;5426.538744206787;5442.857000029959;5449.727513624303;5452.672839629635;5509.090999985037;5510.834674959758;5546.55627549795;5557.221988921554;5569.944300556943;5508.091164957737;5517.834464966058;5523.861903452445;5524.14375771756;5529.723513824292;5512.5589952803675;5513.917291240635;5514.36584792754;5517.117261238182;5517.889642207214;4712.057588482317;4714.104320179155;4715.339852420664;4716.056788642217;4718.4809670936165;4114.691398145092;4115.259253334369;4120.278951183531;4125.958740412558;4172.979135104305;4683.437987441529;4776.522347765195;4784.354112194809;4807.855764327076;4823.638227132979;5579.553635709133;5619.072852979294;5622.465865742748;5650.29371328584;5682.857928551819;4852.539008794159;4857.757112144449;4859.975700121477;4873.31773551703;4906.092372911023;5069.822556210515;5089.185730283137;5101.107306221396;5102.923456148235;5109.489051094861;5145.974270128625;5174.197999310113;5189.351331083614;5216.817411390735;5229.47705229474;5640.266765320482;5651.491365777099;5693.5160511357;5708.058170401837;5721.141828725558;5230.34620672416;5236.293100431393;5270.657407268515;5283.709395983258;5293.761780719799;5503.724813759376;5523.309586301712;5546.112621980464;5548.167774833807;5557.833265002057;5435.918461223006;5583.581231407742;5602.747876345656;5614.578906581941;5634.042212823748;5453.945460545344;5457.945420545744;5466.207399927068;5488.094464413487;5509.531689806251;5410.918836217538;5412.675239485643;5415.83752487426;5421.349438067457;5455.590830687794;5212.739363031757;5341.41244463101;5341.78632854697;5344.091504444176;5352.277442545206;5363.222332761806;5416.674999500043;5485.286912701324;5498;5517.172424136412;5344.465553444625;5364.839054828361;5415.566754659539;5430.348358197041;5430.484104010113 04:50:03 INFO - PID 7885 | |1;ext.html;609.8292478106129;710.1123595505618;722.2344315026071;1068.5084860963952;1068.9358638481697;31644.525332120007;32901.341973160525;32932.50601240983;32947.67052329482;32951.1762205945;34730.8263458683;35076.82461587688;35177.472337914885;35192.64807351926;35229.47155792659;7315.8536829263385;7400.777976660709;7478.850422991537;7480.17718051014;7480.551166930001;7369.115706115247;7412.295831895961;7452.254774522531;7459.776206713807;7481.102267727881;7413.481056326059;7416.36960858325;7452.776416707507;7460.0674915635545;7478.813029674275;7399.556026638419;7406.629668516553;7409;7432.516886402421;7449.925500744977;7352.595607241599;7445.664945077485;7449.255074492562;7465.738699145558;7469;34652;34653.82673086618;34660;34773.30453390918;35093.29813403743;2626.6979297380853;2645.1138868479084;2649.205238428464;2655.415808522122;2697.460507898428;21603;21668.783312166834;21766.89116554407;21998.560028799333;22409.775902240934;2294.736105347873;2308.3421224950807;2350.97649023511;2352.2943117064983;2378.2865140457966;121143.39428302801;123944;134186.329068355;137829;138537.30731346278;39311.41032884509;39389.01527461779;39393.80303098466;39602.60397396047;39763.20473590512;41760.37359439611;41872.1625567493;41883;41967.790161049;42026.78986605047;12672;12763.106582539318;12821.102522823312;12827.743445131046;12875.54935577269;10190.847137292947;10215.182785377152;10215.897841021495;10219.744506387251;10239.385636861813;13224;13315.134516256412;13374.33128343579;13374.732505349839;13633.863661363259;5573.303337082864;5661.915071273934;5665.915011274834;5671.943280567141;5703.287089113861;798.6166640012826;824.2993455562723;833.2334252487726;842.8440738463404;844.7676888855614;20674.896625517078;21585.784142158693;21649.89175054146;21688.891555542123;21702.348929532138;911.480456140007;918.2149262380568;925.708401853414;938.8544775559799;947.0907928388687;11034.393108378956;11094.833577496343;11306.378149201708;11334.319940803578;11384.373859437645;229741;230657;231958;232054;232403;44244.336334955;44367;44571.55428445674;44596.55403445924;44687.55312446899;1211.4427363412929;1241.1312081542776;1245.9314737689515;1247.65689435406;1249.8062800265973;19621.49856281028;20257.59484810266;20273.898630506752;21107.472313192295;21258.893705531373;31305;31366;31390.215244619067;31435.371292574477;31474.370512590078;209210;210273;211194;211311;211357;29662.995495607818;31578.210544736565;31844.6815531851;32019.199520012186;32077.67922320747;39929.60070399375;40310.39534407045;40631.593684062784;41300.38049429201;41560.79219603883;30059.849700751358;30185.245368865955;30241.395172096876;30511.694883050885;30691.846540767154;29529.557056644597;29907.55138672878;30322.393552128393;31216.843915780275;31232;34183.65816341805;34271.14322141966;34585.30829383348;35014.29971400608;35033.82483087568;29225.7077429223;29397.85301073481;29907.850460747555;30152.547711783904;30423.847880760455;23334;24410.389740256636;24451.633225501988;24576.87711561431;24742.628860566747 04:50:03 INFO - PID 7885 | |2;jquery.html;415.45367841288703;418.4079527468632;421.41213007854043;429.83881044608273;432.76846886915496;17797.288108475677;18014.369497067604;18847.811521884792;18871.528211794695;19055.42833714987;17751;17753.91123044386;17767.378141765043;17917.372891948784;18085.64357673173;5557.277553917985;5639.830805075844;5755.30936287644;5787.47912687858;5838.241028666267;5791.536677065846;5828.271466066742;5843.18195452637;5846.976779063647;5847.473727364536;5673.063944449161;5699.800506982257;5757.280339957505;5763.366029736726;5771.653700777967;5762.135679648044;5768.307803063638;5808.273965754239;5823.970880145594;5830.212921255618;5091.338126043628;5172.974135129338;5220.138677118309;5221.164613661834;5224.164133738622;22000.66998995;22297.44256393587;22346.329610111723;22348.553028939332;22466.326010219556;1478.8151481064867;1503.1056521369767;1521.3382178752279;1568.6705791783625;1578.423875285524;13140.342982850914;14010.29948502581;14201.147931124166;14225;14579;1697.236243690332;1698.9405370812085;1702.4637239269714;1719.8968061916325;1736.28812187004;86492;88312;89536.55231723865;90100.5494972521;90826;10467.947660261802;10848.294860834021;11056.336619802676;11171.44142792857;11243.325400475835;15315.46395876139;15394.692106157814;15395.61510962235;15418.845811541742;15450.768238476432;7476.364509016795;7766.766996990099;7882.369410447151;7884.0144981877265;7935.127136015034;7036.859262814715;7114.359707626235;7242.348188663045;7374.631268436557;7407.296306850841;6860.485463590251;7015.228324884259;7032.12098487689;7058.54119482232;7064.646767661597;1984.2658216459713;1994.2920263306607;2035.3690355989395;2052.399128679632;2116.0426605389953;827.7433995461427;843.4011851585443;848.3128665780737;852.4953457148139;879.683314006957;15717;16638.25127869249;16698.4155554555;16788.32846686119;16818.327266909186;872.4198408058696;878.8022694893725;893.1916615462886;901.1079031758643;901.8061116859907;8694.869576956351;8783.868241976375;8836.381453298207;9360.62557497706;9405.247580193569;429723;430395;447106;448719;449780;80295.59852200818;87729.56135219283;87957;89961;90819.54590227139;1032.2464600841429;1043.7442826507554;1061.045059446489;1121.4560937945091;1122.6351435783404;17342.826571734626;17349.82650173482;17879.28482860671;18037;18181.181846817188;42266.788666056476;42588.78705606576;43028.35457468078;43237;43660.126797463236;55675;56320;56568;57901;58130.709346453;8245.175482451707;8306.460080094774;8415.368847336353;8474.110218426977;8650.351223658377;13195.802062969255;13509.392077356346;13544.932275338955;13604.387802548712;13649.658758531115;10843.620473283398;11192.944035279772;11439;11587.884121159017;11858.873407026318;10927.617533386294;11079.889201107886;11088.112950964065;11128.262908933868;11272.718182045515;11654;11795.056395488169;11999.220050696676;12069.75860482803;12233.449494772934;8298.170182981896;8454.788630284293;8570.100139485265;8604.311655067704;8690.826183476422;10544.789104218025;10558.736031599272;10582.788344232918;10680.359178449318;11100.222984391015 04:50:03 INFO - PID 7885 | |3;mootools.html;1201.8858208470194;1257.076049103909;1263.7977923532235;1265.4242319744517;1266.3225174531628;9516.90483095169;9630.566624501897;9828.459434731096;9848.409095454277;9870.111689947904;9863.21094312455;10018.348807327518;10087.848682269754;10092.848607270897;10101.595936162545;2217.5897458970103;2267.183813827021;2270.920517781878;2277.578647950126;2278.134308962592;2158.0720290275176;2212.822974162071;2215.667649852527;2222.088943533152;2243.517643706603;2119.1205649655376;2166.4042388343173;2166.685830554569;2184.5958497677902;2186.9343919682433;2111.767705552388;2122.172352782416;2122.5754849030177;2122.8301735861096;2128.829693624514;1854.1934258597553;1930.5077205312696;2057.2902348689677;2110.2508609443735;2115.428834214769;9549.713508594754;9668.226541876633;9670.516474176264;9694.563744631509;9697.515124243759;5257.76340064698;5450.781968721247;5454.836354909359;5455.263539422167;5455.672659640394;3363.4786608075788;3364.596248450177;3371.4605663094035;3401.744869134825;3453.8791142309897;1012.5443550402348;1023.8617786598788;1039.9392619528114;1084.6420681175193;1094.9124070074374;13980.999999999898;13986.30068496571;14024.08843425174;14044.297785110704;14076.788848167183;4670.672588464101;4799.760011999386;4806.110869489155;4847.80608775652;4914.066327397783;10414.531346089445;10555;10567.630132945464;10568.15454763617;10629.84055239172;5403.2705584746345;5420.620556561003;5429.647072940246;5431.348238211361;5449.536789372948;4727.503612120747;4791.760411979387;4947.332110165082;4976.701397916137;5000.449950505442;3822.140018495816;3903.9609603904164;3933.9606603934167;3963.365861462152;4008.939865902014;1153.682737247247;1164.6913567904512;1170.4030944218339;1175.8471398718284;1190.4880901212389;678.6911955060435;681.5731821817328;688.4389222783416;695.7495301691387;702.7154002628944;5227.947720522822;5228.241904923841;5327.9733601331745;5376.1398176291605;5423.457654234624;535.8392482255385;546.9343678758495;548.3036543589619;549.1488193300448;558.6759679385947;3187.856546455415;3209.5988001499813;3210.614726232867;3257.918552036218;3321.850516726753;12133.27200367963;12837.165584236995;12848.678783030498;12858.742825143632;12863.678408039874;12617.36913154357;12674.873251267554;12692.746145077046;12734.872651273368;12746.980241580837;9085.954570227106;9094.909050909539;9112.635494580276;9219.400738951947;9333.953330233306;5405.216243644728;5416.431274716256;5423.3491980962535;5615.073512870331;5752.252207213036;11137.777244455228;11211.103111750877;11249.493772780408;11263.718407039889;11423.485943132413;11361.261518001635;11473.368964706688;11476.885231147582;11574.88425115738;11638.476268567765;7677.5777332245025;7896.881546776688;7969.721059763112;7971.481853679492;7976.082750483537;8873.112688731075;8904.421212621159;8990.775230619287;8996.59515321799;9007.279417646703;7210.89183662256;7282.96358518204;7292.380147687506;7320.926790732237;7340.302671246223;7154.284571542804;7323.560586364835;7328.81677958034;7391.260873912781;7505.249475052451;6465.3534646536855;6535.477161827138;6548.0832683423405;6606.075149479173;6610.10763546915;4754.405699287589;4818.725783266648;4825.686330388514;4867.878303042452;5015.1975683890405;5118.872028199325;5164.302819119521;5302.522772950453;5354.223637572452;5432.456754324536 04:50:03 INFO - PID 7885 | |4;prototype.html;386.6578078400618;388.1751278533117;391.7199202570163;392.3545767212935;395.5767328958015;5480.6437581557175;5567.164925261208;5568.749406276717;5622.678670512426;5698.886022279562;5402.78388864445;5472.20653005314;5489.752961116749;5496.065668836298;5502.174673798932;4153.626173644371;4244.172386384648;4269.87190384289;4273.166732487166;4309.806058727349;4268.286590666109;4280.678949078816;4284.550122237167;4286.699931004831;4301.526832048487;4243.215005224043;4251.638610718092;4271.252530807112;4278.1871444425615;4282.635975942048;4086.4255519023122;4201.327787553977;4221.936670949938;4239.342901850218;4284.550122237167;3669.321175582526;3674.889753307405;3694.704423646102;3697.371446854041;3698.130939229279;5372.167314066326;5390.299261096072;5390.838274851761;5391.514763671288;5415.48552887475;4714.599259062984;4844.868257966484;4885.2427873679635;4891.144049791273;4898.755062246873;5807.070868661036;5810.099434587646;5818.330891947396;5821.941780582225;5825.359210486843;4059.4316795648633;4059.6955228357697;4061.1471590965934;4064.552899181088;4070.5725898780793;5982.341942386334;6095.6952152392205;6125.203723515959;6141.570090093695;6143.232095988046;4142.523609784853;4166.645835103989;4169.249535083714;4173.373993900878;4196.244675958357;4214.726042807208;4217.662586993033;4230.534641189467;4241.409471448207;4266.850660226877;4768.451628062748;4881.731504767272;4894.681845680019;4895.069936712013;4911.484294149063;5828.271466066742;5836.824895253149;5852.15143804146;5854.531637468992;5862.895972819044;1454.5200083972263;1468.6255004973877;1471.0732238689557;1471.4261438039173;1475.697482016184;900.0255689082124;961.1541843177959;965.2133511188359;974.3374505336297;983.9360441571275;5053.1157047516535;5072.340595722532;5080.618953578422;5082.186850103965;5091.618128640367;6033.547483938634;6079.787207447806;6118.816435506942;6120.571559990758;6123.785667501616;4862.322498738001;5041.294218809369;5080.060188865072;5089.185730283211;5096.413912400048;3192.393445245396;3232.143481977278;3242.8702851886133;3250.756193285513;3262.429074911881;5520.475554822286;5675.489205971482;5678.4605462480995;5680.573956953245;5683.744231509592;6104.236970378703;6162.661053642093;6174.722137503733;6177.62934223948;6186.19579454668;5475.206095116186;5653.547716182696;5654.37801841797;5666.46168613981;5687.744051517609;6063.151158837678;6092.055731361646;6092.177556029879;6096.207493025879;6110.7861224856915;5769.105788602773;5963.91054134197;5975.940240597538;5978.611390259794;5978.641281523122;5618.2415373924;5638.182463542846;5640.041192997282;5645.266115404971;5660.971695141498;4180.498340199196;4183.539810620769;4188.2879910414695;4190.790460477025;4191.664666826708;5761.942380576141;5970.641761494324;6055.333913269449;6056.788012419721;6072.271327440735;5765.740541675551;5801.912971305349;5805.29770885584;5830.708464576669;5840.532757379315;5640.294963129609;5818.796342128006;5854.336276993872;5875.030619947661;5875.7084736953475;5755.5683323750045;5758.798442054508;5762;5778.942210578009;5796.739146738491;5877.882442351214;5878.470937615464;5904.793332233351;5906;5915;5471.714147175331;5699;5731.684757338387;5738.368779434176;5763.827085187451 04:50:03 INFO - PID 7885 | |5;yui.html;593.5874567175812;594.0702800117813;614.9354317796631;626.7962912053583;632.8354627796773;11941.940290298548;12251.816222756666;12293;12711.682207944783;14098.365573549188;13203.141795783267;13537.458501659921;13595.18428894267;13601.31993400331;13671.453141874337;855.0423525651264;877.1623099939543;878.8066625342415;881.2861582118504;881.9647214111428;879.8548239540468;894.2756367342473;896.860986547083;912.3385545479507;919.0304229038362;962.7304354780638;962.8796400449944;966.3042609321277;966.5602151021303;968.239931653652;947.0907928388755;949.952502374882;953.3755390219418;953.4803532075001;953.5851904421564;702.5995182745837;706.5054461876707;707.0419581467141;708.1572928215459;708.3518580498837;13284.601461956061;13437.798433023512;13465.778146605799;13615.727685446236;14519;12633.93683031588;12641.747165056739;12686.302253376061;12797.360131993362;13188.032138146056;8286.254237118688;8532.36007299455;8599.226069653698;8637.870431943464;8676.305895528343;2327.5461285049423;2328.1851352026824;2329.94175145621;2393.628987506939;2426.0295881647307;62105.68947155235;62412.37587624157;62455.37544624587;63054.99999999954;63424.04863927045;9740.951295243478;9762.36544624597;9807.215422766161;10045.397276163454;10216.506107855854;21408.146533623785;21480.462988425414;21619.67570486444;21670.674939875913;21681;5890.646561206341;6055.72749226277;6389.680515974275;6474.546781725234;6480.384363485461;2855.8714857831446;2926.2099233206914;2937.2803663102677;2952.232419570927;3028.3337665713507;3256.1534001159393;3291.983540082332;3318.900432987014;3322.2691007978688;3331.6501767314567;640.5900223856736;642.408983734965;642.6626021338741;658.3285049249738;658.9110470086573;466.6756604160076;468.08255818595933;471.25071136892507;488.2578480709373;493.6519753573722;6829.863402731918;7064.004463573994;7065.116860392451;7379.3358597726465;7504.6247687615405;458.8164734106317;461.7898856020503;479.9304100905419;485.37895365028425;501.47846239910086;3115;3302.6036875575087;3368.2926585417335;3380.678835510623;3493.318802833423;60487.39512604906;60843.695781521696;61303;61457.38542614606;62907;38343.808280958416;42129;49731.751341243784;53691.731541342044;53967;44951.55048449539;45810;46122.308165377544;46255.76872115618;46273.30590041085;7671.968151478549;8006.639701213343;8058.274755272054;8064.838703225785;8235.588220588827;23066;24332.87833560821;24482;25040.62439063452;25183.37041573975;39927.401088983104;41010.589894100674;41057.794711026254;41399.37900931428;41712;10053.69838904834;10132.960111966528;10217.948910255402;10218.284720069523;10269.075783179549;11041.171912106463;11055.889441105486;11199.160062995144;11268.38023908693;11282.717932051764;10047.598096076075;10065.446400448045;10192.949035254776;10378.844317335095;10424.322419042737;11048.944755276172;11150.888491115309;11191.351866466044;11375.829362559403;11675.591354302898;10250.016208910376;10336.483175841327;10448.52981615814;10524.894751052392;10811.675649730521;7686.807829804299;7881.605919704107;7957.56233407145;7993.480423772437;8057.5165490070785;8141.8371632568205;8405.915940840514;8572.099929507312;8727.956360218412;8948.373613847229 04:50:03 INFO - PID 7885 | __end_tp_report 04:50:03 INFO - PID 7885 | __start_cc_report 04:50:03 INFO - PID 7885 | _x_x_mozilla_cycle_collect,387 04:50:03 INFO - PID 7885 | __end_cc_report 04:50:03 INFO - PID 7885 | __startTimestamp1490961003959__endTimestamp 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | ------- Summary: start ------- 04:50:03 INFO - PID 7885 | Number of tests: 6 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | [#0] dojo.html Cycles:165 Average:5153.59 Median:5283.71 stddev:573.26 (10.8%) stddev-sans-first:541.20 04:50:03 INFO - PID 7885 | Values: 2680.9 2708.4 2715.2 2724.4 2744.9 5105.8 5200.5 5207.4 5254.6 5254.9 5166.5 5189.1 5218.3 5282.3 5291.6 4752.1 4788.2 4814.3 4817.9 4846.6 4827.2 4861.9 4886.0 4905.5 4940.4 4638.1 4682.3 4686.3 4822.0 4826.9 4710.6 4731.2 4767.0 4810.0 4821.3 4646.9 4675.0 4712.6 4736.7 4755.4 5062.5 5112.7 5162.8 5173.5 5232.8 5615.6 5648.1 5663.3 5680.0 5682.8 5679.3 5682.5 5683.5 5684.2 5704.9 5177.5 5232.1 5266.2 5281.6 5327.5 5653.6 5662.9 5739.2 5801.5 5834.2 5382.1 5426.5 5442.9 5449.7 5452.7 5509.1 5510.8 5546.6 5557.2 5569.9 5508.1 5517.8 5523.9 5524.1 5529.7 5512.6 5513.9 5514.4 5517.1 5517.9 4712.1 4714.1 4715.3 4716.1 4718.5 4114.7 4115.3 4120.3 4126.0 4173.0 4683.4 4776.5 4784.4 4807.9 4823.6 5579.6 5619.1 5622.5 5650.3 5682.9 4852.5 4857.8 4860.0 4873.3 4906.1 5069.8 5089.2 5101.1 5102.9 5109.5 5146.0 5174.2 5189.4 5216.8 5229.5 5640.3 5651.5 5693.5 5708.1 5721.1 5230.3 5236.3 5270.7 5283.7 5293.8 5503.7 5523.3 5546.1 5548.2 5557.8 5435.9 5583.6 5602.7 5614.6 5634.0 5453.9 5457.9 5466.2 5488.1 5509.5 5410.9 5412.7 5415.8 5421.3 5455.6 5212.7 5341.4 5341.8 5344.1 5352.3 5363.2 5416.7 5485.3 5498.0 5517.2 5344.5 5364.8 5415.6 5430.3 5430.5 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | [#1] ext.html Cycles:180 Average:33827.56 Median:31412.79 stddev:51019.36 (162.4%) stddev-sans-first:51101.52 04:50:03 INFO - PID 7885 | Values: 609.8 710.1 722.2 1068.5 1068.9 31644.5 32901.3 32932.5 32947.7 32951.2 34730.8 35076.8 35177.5 35192.6 35229.5 7315.9 7400.8 7478.9 7480.2 7480.6 7369.1 7412.3 7452.3 7459.8 7481.1 7413.5 7416.4 7452.8 7460.1 7478.8 7399.6 7406.6 7409.0 7432.5 7449.9 7352.6 7445.7 7449.3 7465.7 7469.0 34652.0 34653.8 34660.0 34773.3 35093.3 2626.7 2645.1 2649.2 2655.4 2697.5 21603.0 21668.8 21766.9 21998.6 22409.8 2294.7 2308.3 2351.0 2352.3 2378.3 121143.4 123944.0 134186.3 137829.0 138537.3 39311.4 39389.0 39393.8 39602.6 39763.2 41760.4 41872.2 41883.0 41967.8 42026.8 12672.0 12763.1 12821.1 12827.7 12875.5 10190.8 10215.2 10215.9 10219.7 10239.4 13224.0 13315.1 13374.3 13374.7 13633.9 5573.3 5661.9 5665.9 5671.9 5703.3 798.6 824.3 833.2 842.8 844.8 20674.9 21585.8 21649.9 21688.9 21702.3 911.5 918.2 925.7 938.9 947.1 11034.4 11094.8 11306.4 11334.3 11384.4 229741.0 230657.0 231958.0 232054.0 232403.0 44244.3 44367.0 44571.6 44596.6 44687.6 1211.4 1241.1 1245.9 1247.7 1249.8 19621.5 20257.6 20273.9 21107.5 21258.9 31305.0 31366.0 31390.2 31435.4 31474.4 209210.0 210273.0 211194.0 211311.0 211357.0 29663.0 31578.2 31844.7 32019.2 32077.7 39929.6 40310.4 40631.6 41300.4 41560.8 30059.8 30185.2 30241.4 30511.7 30691.8 29529.6 29907.6 30322.4 31216.8 31232.0 34183.7 34271.1 34585.3 35014.3 35033.8 29225.7 29397.9 29907.9 30152.5 30423.8 23334.0 24410.4 24451.6 24576.9 24742.6 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | [#2] jquery.html Cycles:180 Average:27615.62 Median:215076.42 stddev:73129.21 (34.0%) stddev-sans-first:73305.84 04:50:03 INFO - PID 7885 | Values: 415.5 418.4 421.4 429.8 432.8 17797.3 18014.4 18847.8 18871.5 19055.4 17751.0 17753.9 17767.4 17917.4 18085.6 5557.3 5639.8 5755.3 5787.5 5838.2 5791.5 5828.3 5843.2 5847.0 5847.5 5673.1 5699.8 5757.3 5763.4 5771.7 5762.1 5768.3 5808.3 5824.0 5830.2 5091.3 5173.0 5220.1 5221.2 5224.2 22000.7 22297.4 22346.3 22348.6 22466.3 1478.8 1503.1 1521.3 1568.7 1578.4 13140.3 14010.3 14201.1 14225.0 14579.0 1697.2 1698.9 1702.5 1719.9 1736.3 86492.0 88312.0 89536.6 90100.5 90826.0 10467.9 10848.3 11056.3 11171.4 11243.3 15315.5 15394.7 15395.6 15418.8 15450.8 7476.4 7766.8 7882.4 7884.0 7935.1 7036.9 7114.4 7242.3 7374.6 7407.3 6860.5 7015.2 7032.1 7058.5 7064.6 1984.3 1994.3 2035.4 2052.4 2116.0 827.7 843.4 848.3 852.5 879.7 15717.0 16638.3 16698.4 16788.3 16818.3 872.4 878.8 893.2 901.1 901.8 8694.9 8783.9 8836.4 9360.6 9405.2 429723.0 430395.0 447106.0 448719.0 449780.0 80295.6 87729.6 87957.0 89961.0 90819.5 1032.2 1043.7 1061.0 1121.5 1122.6 17342.8 17349.8 17879.3 18037.0 18181.2 42266.8 42588.8 43028.4 43237.0 43660.1 55675.0 56320.0 56568.0 57901.0 58130.7 8245.2 8306.5 8415.4 8474.1 8650.4 13195.8 13509.4 13544.9 13604.4 13649.7 10843.6 11192.9 11439.0 11587.9 11858.9 10927.6 11079.9 11088.1 11128.3 11272.7 11654.0 11795.1 11999.2 12069.8 12233.4 8298.2 8454.8 8570.1 8604.3 8690.8 10544.8 10558.7 10582.8 10680.4 11100.2 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | [#3] mootools.html Cycles:180 Average:6025.35 Median:4740.95 stddev:3846.38 (81.1%) stddev-sans-first:3840.09 04:50:03 INFO - PID 7885 | Values: 1201.9 1257.1 1263.8 1265.4 1266.3 9516.9 9630.6 9828.5 9848.4 9870.1 9863.2 10018.3 10087.8 10092.8 10101.6 2217.6 2267.2 2270.9 2277.6 2278.1 2158.1 2212.8 2215.7 2222.1 2243.5 2119.1 2166.4 2166.7 2184.6 2186.9 2111.8 2122.2 2122.6 2122.8 2128.8 1854.2 1930.5 2057.3 2110.3 2115.4 9549.7 9668.2 9670.5 9694.6 9697.5 5257.8 5450.8 5454.8 5455.3 5455.7 3363.5 3364.6 3371.5 3401.7 3453.9 1012.5 1023.9 1039.9 1084.6 1094.9 13981.0 13986.3 14024.1 14044.3 14076.8 4670.7 4799.8 4806.1 4847.8 4914.1 10414.5 10555.0 10567.6 10568.2 10629.8 5403.3 5420.6 5429.6 5431.3 5449.5 4727.5 4791.8 4947.3 4976.7 5000.4 3822.1 3904.0 3934.0 3963.4 4008.9 1153.7 1164.7 1170.4 1175.8 1190.5 678.7 681.6 688.4 695.7 702.7 5227.9 5228.2 5328.0 5376.1 5423.5 535.8 546.9 548.3 549.1 558.7 3187.9 3209.6 3210.6 3257.9 3321.9 12133.3 12837.2 12848.7 12858.7 12863.7 12617.4 12674.9 12692.7 12734.9 12747.0 9086.0 9094.9 9112.6 9219.4 9334.0 5405.2 5416.4 5423.3 5615.1 5752.3 11137.8 11211.1 11249.5 11263.7 11423.5 11361.3 11473.4 11476.9 11574.9 11638.5 7677.6 7896.9 7969.7 7971.5 7976.1 8873.1 8904.4 8990.8 8996.6 9007.3 7210.9 7283.0 7292.4 7320.9 7340.3 7154.3 7323.6 7328.8 7391.3 7505.2 6465.4 6535.5 6548.1 6606.1 6610.1 4754.4 4818.7 4825.7 4867.9 5015.2 5118.9 5164.3 5302.5 5354.2 5432.5 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | [#4] prototype.html Cycles:180 Average:4821.92 Median:5492.91 stddev:1420.50 (25.9%) stddev-sans-first:1384.93 04:50:03 INFO - PID 7885 | Values: 386.7 388.2 391.7 392.4 395.6 5480.6 5567.2 5568.7 5622.7 5698.9 5402.8 5472.2 5489.8 5496.1 5502.2 4153.6 4244.2 4269.9 4273.2 4309.8 4268.3 4280.7 4284.6 4286.7 4301.5 4243.2 4251.6 4271.3 4278.2 4282.6 4086.4 4201.3 4221.9 4239.3 4284.6 3669.3 3674.9 3694.7 3697.4 3698.1 5372.2 5390.3 5390.8 5391.5 5415.5 4714.6 4844.9 4885.2 4891.1 4898.8 5807.1 5810.1 5818.3 5821.9 5825.4 4059.4 4059.7 4061.1 4064.6 4070.6 5982.3 6095.7 6125.2 6141.6 6143.2 4142.5 4166.6 4169.2 4173.4 4196.2 4214.7 4217.7 4230.5 4241.4 4266.9 4768.5 4881.7 4894.7 4895.1 4911.5 5828.3 5836.8 5852.2 5854.5 5862.9 1454.5 1468.6 1471.1 1471.4 1475.7 900.0 961.2 965.2 974.3 983.9 5053.1 5072.3 5080.6 5082.2 5091.6 6033.5 6079.8 6118.8 6120.6 6123.8 4862.3 5041.3 5080.1 5089.2 5096.4 3192.4 3232.1 3242.9 3250.8 3262.4 5520.5 5675.5 5678.5 5680.6 5683.7 6104.2 6162.7 6174.7 6177.6 6186.2 5475.2 5653.5 5654.4 5666.5 5687.7 6063.2 6092.1 6092.2 6096.2 6110.8 5769.1 5963.9 5975.9 5978.6 5978.6 5618.2 5638.2 5640.0 5645.3 5661.0 4180.5 4183.5 4188.3 4190.8 4191.7 5761.9 5970.6 6055.3 6056.8 6072.3 5765.7 5801.9 5805.3 5830.7 5840.5 5640.3 5818.8 5854.3 5875.0 5875.7 5755.6 5758.8 5762.0 5778.9 5796.7 5877.9 5878.5 5904.8 5906.0 5915.0 5471.7 5699.0 5731.7 5738.4 5763.8 04:50:03 INFO - PID 7885 | 04:50:03 INFO - PID 7885 | [#5] yui.html Cycles:180 Average:13484.70 Median:23369.99 stddev:16776.87 (71.8%) stddev-sans-first:16796.00 04:50:03 INFO - PID 7885 | Values: 593.6 594.1 614.9 626.8 632.8 11941.9 12251.8 12293.0 12711.7 14098.4 13203.1 13537.5 13595.2 13601.3 13671.5 855.0 877.2 878.8 881.3 882.0 879.9 894.3 896.9 912.3 919.0 962.7 962.9 966.3 966.6 968.2 947.1 950.0 953.4 953.5 953.6 702.6 706.5 707.0 708.2 708.4 13284.6 13437.8 13465.8 13615.7 14519.0 12633.9 12641.7 12686.3 12797.4 13188.0 8286.3 8532.4 8599.2 8637.9 8676.3 2327.5 2328.2 2329.9 2393.6 2426.0 62105.7 62412.4 62455.4 63055.0 63424.0 9741.0 9762.4 9807.2 10045.4 10216.5 21408.1 21480.5 21619.7 21670.7 21681.0 5890.6 6055.7 6389.7 6474.5 6480.4 2855.9 2926.2 2937.3 2952.2 3028.3 3256.2 3292.0 3318.9 3322.3 3331.7 640.6 642.4 642.7 658.3 658.9 466.7 468.1 471.3 488.3 493.7 6829.9 7064.0 7065.1 7379.3 7504.6 458.8 461.8 479.9 485.4 501.5 3115.0 3302.6 3368.3 3380.7 3493.3 60487.4 60843.7 61303.0 61457.4 62907.0 38343.8 42129.0 49731.8 53691.7 53967.0 44951.6 45810.0 46122.3 46255.8 46273.3 7672.0 8006.6 8058.3 8064.8 8235.6 23066.0 24332.9 24482.0 25040.6 25183.4 39927.4 41010.6 41057.8 41399.4 41712.0 10053.7 10133.0 10217.9 10218.3 10269.1 11041.2 11055.9 11199.2 11268.4 11282.7 10047.6 10065.4 10192.9 10378.8 10424.3 11048.9 11150.9 11191.4 11375.8 11675.6 10250.0 10336.5 10448.5 10524.9 10811.7 7686.8 7881.6 7957.6 7993.5 8057.5 8141.8 8405.9 8572.1 8728.0 8948.4 04:50:03 INFO - PID 7885 | -------- Summary: end -------- 04:50:03 INFO - PID 7885 | 04:50:04 INFO - TEST-INFO | 7885: exit 0 04:50:04 INFO - TEST-OK | dromaeo_css | took 1121538ms 04:50:04 INFO - TEST-START | kraken 04:50:04 INFO - operating with platform_type : linux_ 04:50:04 INFO - Initialising browser for kraken test... 04:50:04 INFO - TEST-INFO | started process 8260 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTrm4Zs/profile http://localhost:55892/getInfo.html) 04:50:09 INFO - PID 8260 | __metrics Screen width/height:1600/1200 04:50:09 INFO - PID 8260 | colorDepth:24 04:50:09 INFO - PID 8260 | Browser inner width/height: 1024/697 04:50:09 INFO - PID 8260 | __metrics 04:50:10 ERROR - PID 8260 | 1490961010580 addons.xpi WARN Exception running bootstrap method shutdown on shield-recipe-client@mozilla.org: TypeError: log is null (resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@mozilla.org.xpi!/bootstrap.js:96:5) JS Stack trace: this.shutdown@resource://gre/modules/addons/XPIProvider.jsm -> jar:file:///builds/slave/test/build/application/firefox/browser/features/shield-recipe-client@bootstrap.js:96:5 < callBootstrapMethod@XPIProvider.jsm:4957:11 < observe@XPIProvider.jsm:2924:15 < permitUnload@remote-browser.xml:357:13 < CanCloseWindow@browser.js:6382:36 < canClose@browser.js:5233:12 < forceQuit@jar:file:///tmp/tmpTrm4Zs/profile/extensions/talos-powers@TalosPowersService.js:237:7 < whenDone@jar:file:///tmp/tmpTrm4Zs/profile/extensions/talos-powers@TalosPowersService.js:222:9 < promise callback*forceQuit@jar:file:///tmp/tmpTrm4Zs/profile/extensions/talos-powers@TalosPowersService.js:224:7 < receiveMessage@jar:file:///tmp/tmpTrm4Zs/profile/extensions/talos-powers@TalosPowersService.js:55:9 04:50:10 ERROR - PID 8260 | JavaScript error: chrome://browser/content/tabbrowser.xml, line 3396: TypeError: this.tabs is undefined 04:50:11 INFO - TEST-INFO | 8260: exit 0 04:50:11 INFO - Browser initialized. 04:50:11 INFO - Running cycle 1/1 for kraken test... 04:50:11 INFO - TEST-INFO | started process 8388 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpTrm4Zs/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 04:50:23 INFO - PID 8388 | RSS: Main: 155246592 04:50:23 INFO - PID 8388 | 04:50:26 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:55892/tests/kraken/driver.html?testName=audio-beat-detection) 04:50:27 INFO - PID 8388 | RSS: Main: 161460224 04:50:27 INFO - PID 8388 | 04:50:30 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:55892/tests/kraken/driver.html?testName=audio-dft) 04:50:30 INFO - PID 8388 | RSS: Main: 160980992 04:50:30 INFO - PID 8388 | 04:50:33 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:55892/tests/kraken/driver.html?testName=audio-fft) 04:50:34 INFO - PID 8388 | RSS: Main: 157634560 04:50:34 INFO - PID 8388 | 04:50:36 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:55892/tests/kraken/driver.html?testName=audio-oscillator) 04:50:37 INFO - PID 8388 | RSS: Main: 157986816 04:50:37 INFO - PID 8388 | 04:50:39 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:55892/tests/kraken/driver.html?testName=imaging-gaussian-blur) 04:50:40 INFO - PID 8388 | RSS: Main: 158294016 04:50:40 INFO - PID 8388 | 04:50:43 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:55892/tests/kraken/driver.html?testName=imaging-darkroom) 04:50:43 INFO - PID 8388 | RSS: Main: 163946496 04:50:43 INFO - PID 8388 | 04:50:47 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:55892/tests/kraken/driver.html?testName=imaging-desaturate) 04:50:48 INFO - PID 8388 | RSS: Main: 161329152 04:50:48 INFO - PID 8388 | 04:50:51 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:55892/tests/kraken/driver.html?testName=json-parse-financial) 04:50:51 INFO - PID 8388 | RSS: Main: 162492416 04:50:51 INFO - PID 8388 | 04:50:53 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:55892/tests/kraken/driver.html?testName=json-stringify-tinderbox) 04:50:54 INFO - PID 8388 | RSS: Main: 163184640 04:50:54 INFO - PID 8388 | 04:50:55 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-aes) 04:50:56 INFO - PID 8388 | RSS: Main: 163016704 04:50:56 INFO - PID 8388 | 04:50:58 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-ccm) 04:50:59 INFO - PID 8388 | RSS: Main: 163328000 04:50:59 INFO - PID 8388 | 04:51:02 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 04:51:03 INFO - PID 8388 | RSS: Main: 163741696 04:51:03 INFO - PID 8388 | 04:51:06 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 04:51:06 INFO - PID 8388 | RSS: Main: 163332096 04:51:06 INFO - PID 8388 | 04:51:08 INFO - PID 8388 | Cycle 1(1): loaded http://localhost:55892/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:55892/tests/kraken/driver.html?testName=ai-astar) 04:51:09 INFO - PID 8388 | RSS: Main: 165335040 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | __start_tp_report 04:51:09 INFO - PID 8388 | _x_x_mozilla_page_load 04:51:09 INFO - PID 8388 | _x_x_mozilla_page_load_details 04:51:09 INFO - PID 8388 | |i|pagename|runs| 04:51:09 INFO - PID 8388 | |0;ai-astar;104;114;113;113;113;114;117;113;112;114 04:51:09 INFO - PID 8388 | |1;audio-beat-detection;152;130;128;172;133;137;136;128;151;132 04:51:09 INFO - PID 8388 | |2;audio-dft;168;166;168;174;176;167;167;169;169;163 04:51:09 INFO - PID 8388 | |3;audio-fft;77;77;76;121;76;79;79;76;93;75 04:51:09 INFO - PID 8388 | |4;audio-oscillator;84;86;110;85;86;85;99;84;86;112 04:51:09 INFO - PID 8388 | |5;imaging-gaussian-blur;115;114;116;115;114;120;114;114;114;115 04:51:09 INFO - PID 8388 | |6;imaging-darkroom;152;151;151;151;152;152;153;152;151;152 04:51:09 INFO - PID 8388 | |7;imaging-desaturate;94;95;95;94;96;96;95;95;95;95 04:51:09 INFO - PID 8388 | |8;json-parse-financial;68;68;68;69;69;70;70;69;70;68 04:51:09 INFO - PID 8388 | |9;json-stringify-tinderbox;56;57;59;57;56;56;57;56;56;56 04:51:09 INFO - PID 8388 | |10;stanford-crypto-aes;84;83;83;92;84;83;86;92;92;84 04:51:09 INFO - PID 8388 | |11;stanford-crypto-ccm;144;160;140;144;135;143;137;152;192;142 04:51:09 INFO - PID 8388 | |12;stanford-crypto-pbkdf2;196;198;196;203;212;192;198;201;196;193 04:51:09 INFO - PID 8388 | |13;stanford-crypto-sha256-iterative;72;74;72;75;74;73;72;71;72;75 04:51:09 INFO - PID 8388 | __end_tp_report 04:51:09 INFO - PID 8388 | __start_cc_report 04:51:09 INFO - PID 8388 | _x_x_mozilla_cycle_collect,870 04:51:09 INFO - PID 8388 | __end_cc_report 04:51:09 INFO - PID 8388 | __startTimestamp1490961069078__endTimestamp 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | ------- Summary: start ------- 04:51:09 INFO - PID 8388 | Number of tests: 14 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#0] ai-astar Cycles:10 Average:112.70 Median:113.50 stddev:3.33 (2.9%) stddev-sans-first:1.41 04:51:09 INFO - PID 8388 | Values: 104.0 114.0 113.0 113.0 113.0 114.0 117.0 113.0 112.0 114.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#1] audio-beat-detection Cycles:10 Average:139.90 Median:136.50 stddev:14.20 (10.4%) stddev-sans-first:14.37 04:51:09 INFO - PID 8388 | Values: 152.0 130.0 128.0 172.0 133.0 137.0 136.0 128.0 151.0 132.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#2] audio-dft Cycles:10 Average:168.70 Median:168.50 stddev:3.77 (2.2%) stddev-sans-first:3.99 04:51:09 INFO - PID 8388 | Values: 168.0 166.0 168.0 174.0 176.0 167.0 167.0 169.0 169.0 163.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#3] audio-fft Cycles:10 Average:82.90 Median:77.00 stddev:14.37 (18.7%) stddev-sans-first:15.08 04:51:09 INFO - PID 8388 | Values: 77.0 77.0 76.0 121.0 76.0 79.0 79.0 76.0 93.0 75.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#4] audio-oscillator Cycles:10 Average:91.70 Median:85.50 stddev:11.09 (13.0%) stddev-sans-first:11.40 04:51:09 INFO - PID 8388 | Values: 84.0 86.0 110.0 85.0 86.0 85.0 99.0 84.0 86.0 112.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#5] imaging-gaussian-blur Cycles:10 Average:115.10 Median:115.00 stddev:1.85 (1.6%) stddev-sans-first:1.96 04:51:09 INFO - PID 8388 | Values: 115.0 114.0 116.0 115.0 114.0 120.0 114.0 114.0 114.0 115.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#6] imaging-darkroom Cycles:10 Average:151.70 Median:152.00 stddev:0.67 (0.4%) stddev-sans-first:0.71 04:51:09 INFO - PID 8388 | Values: 152.0 151.0 151.0 151.0 152.0 152.0 153.0 152.0 151.0 152.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#7] imaging-desaturate Cycles:10 Average:95.00 Median:95.00 stddev:0.67 (0.7%) stddev-sans-first:0.60 04:51:09 INFO - PID 8388 | Values: 94.0 95.0 95.0 94.0 96.0 96.0 95.0 95.0 95.0 95.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#8] json-parse-financial Cycles:10 Average:68.90 Median:69.00 stddev:0.88 (1.3%) stddev-sans-first:0.87 04:51:09 INFO - PID 8388 | Values: 68.0 68.0 68.0 69.0 69.0 70.0 70.0 69.0 70.0 68.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#9] json-stringify-tinderbox Cycles:10 Average:56.60 Median:56.50 stddev:0.97 (1.7%) stddev-sans-first:1.00 04:51:09 INFO - PID 8388 | Values: 56.0 57.0 59.0 57.0 56.0 56.0 57.0 56.0 56.0 56.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#10] stanford-crypto-aes Cycles:10 Average:86.30 Median:85.00 stddev:4.03 (4.7%) stddev-sans-first:4.19 04:51:09 INFO - PID 8388 | Values: 84.0 83.0 83.0 92.0 84.0 83.0 86.0 92.0 92.0 84.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#11] stanford-crypto-ccm Cycles:10 Average:148.90 Median:144.00 stddev:16.78 (11.7%) stddev-sans-first:17.71 04:51:09 INFO - PID 8388 | Values: 144.0 160.0 140.0 144.0 135.0 143.0 137.0 152.0 192.0 142.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:198.50 Median:198.00 stddev:5.78 (2.9%) stddev-sans-first:6.06 04:51:09 INFO - PID 8388 | Values: 196.0 198.0 196.0 203.0 212.0 192.0 198.0 201.0 196.0 193.0 04:51:09 INFO - PID 8388 | 04:51:09 INFO - PID 8388 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:73.00 Median:73.50 stddev:1.41 (1.9%) stddev-sans-first:1.45 04:51:09 INFO - PID 8388 | Values: 72.0 74.0 72.0 75.0 74.0 73.0 72.0 71.0 72.0 75.0 04:51:09 INFO - PID 8388 | -------- Summary: end -------- 04:51:09 INFO - PID 8388 | 04:51:10 INFO - TEST-INFO | 8388: exit 0 04:51:10 INFO - TEST-OK | kraken | took 65398ms 04:51:10 INFO - SUITE-END | took 1186s 04:51:10 INFO - Completed test suite (00:19:47) 04:51:10 INFO - javascript benchmark 04:51:10 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": ["e10s"], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 6576.611476236816, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [2680.9463810723787, 2708.377073273146, 2715.2261605442445, 2724.359775452768, 2744.890204391824, 5105.795768169269, 5200.493768167879, 5207.401148867882, 5254.632175747694, 5254.947450525494, 5166.535011848933, 5189.117849965505, 5218.347706536683, 5282.339707536558, 5291.550218231455, 4752.120857641339, 4788.186008378584, 4814.277858321245, 4817.879553011167, 4846.563809257166, 4827.155247831633, 4861.857463496093, 4886.022795440908, 4905.533974272438, 4940.357753492041, 4638.11875743611, 4682.3210634458155, 4686.320483529903, 4821.951780482185, 4826.903461930777, 4710.599599034086, 4731.219348807443, 4767.046590681877, 4809.975950120262, 4821.276808478721, 4646.883827904296, 4675.0, 4712.599429048535, 4736.739479328636, 4755.35802666639, 5062.493750624908, 5112.718800465973, 5162.771970114961, 5173.456787037345, 5232.816851410183, 5615.606907516435, 5648.096304591287, 5663.2637757091725, 5679.977604031273, 5682.801101961411, 5679.261695979538, 5682.488576028136, 5683.488486036276, 5684.204211410406, 5704.942950570483, 5177.456367081478, 5232.110541208004, 5266.21006848968, 5281.603879708999, 5327.493888080626, 5653.575981801381, 5662.943370566324, 5739.16782066598, 5801.477866991991, 5834.241548598655, 5382.138857782736, 5426.538744206787, 5442.857000029959, 5449.727513624303, 5452.672839629635, 5509.090999985037, 5510.834674959758, 5546.55627549795, 5557.221988921554, 5569.944300556943, 5508.091164957737, 5517.834464966058, 5523.861903452445, 5524.14375771756, 5529.723513824292, 5512.5589952803675, 5513.917291240635, 5514.36584792754, 5517.117261238182, 5517.889642207214, 4712.057588482317, 4714.104320179155, 4715.339852420664, 4716.056788642217, 4718.4809670936165, 4114.691398145092, 4115.259253334369, 4120.278951183531, 4125.958740412558, 4172.979135104305, 4683.437987441529, 4776.522347765195, 4784.354112194809, 4807.855764327076, 4823.638227132979, 5579.553635709133, 5619.072852979294, 5622.465865742748, 5650.29371328584, 5682.857928551819, 4852.539008794159, 4857.757112144449, 4859.975700121477, 4873.31773551703, 4906.092372911023, 5069.822556210515, 5089.185730283137, 5101.107306221396, 5102.923456148235, 5109.489051094861, 5145.974270128625, 5174.197999310113, 5189.351331083614, 5216.817411390735, 5229.47705229474, 5640.266765320482, 5651.491365777099, 5693.5160511357, 5708.058170401837, 5721.141828725558, 5230.34620672416, 5236.293100431393, 5270.657407268515, 5283.709395983258, 5293.761780719799, 5503.724813759376, 5523.309586301712, 5546.112621980464, 5548.167774833807, 5557.833265002057, 5435.918461223006, 5583.581231407742, 5602.747876345656, 5614.578906581941, 5634.042212823748, 5453.945460545344, 5457.945420545744, 5466.207399927068, 5488.094464413487, 5509.531689806251, 5410.918836217538, 5412.675239485643, 5415.83752487426, 5421.349438067457, 5455.590830687794, 5212.739363031757, 5341.41244463101, 5341.78632854697, 5344.091504444176, 5352.277442545206, 5363.222332761806, 5416.674999500043, 5485.286912701324, 5498.0, 5517.172424136412, 5344.465553444625, 5364.839054828361, 5415.566754659539, 5430.348358197041, 5430.484104010113], "value": 5113.253844107521, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [609.8292478106129, 710.1123595505618, 722.2344315026071, 1068.5084860963952, 1068.9358638481697, 31644.525332120007, 32901.341973160525, 32932.50601240983, 32947.67052329482, 32951.1762205945, 34730.8263458683, 35076.82461587688, 35177.472337914885, 35192.64807351926, 35229.47155792659, 7315.8536829263385, 7400.777976660709, 7478.850422991537, 7480.17718051014, 7480.551166930001, 7369.115706115247, 7412.295831895961, 7452.254774522531, 7459.776206713807, 7481.102267727881, 7413.481056326059, 7416.36960858325, 7452.776416707507, 7460.0674915635545, 7478.813029674275, 7399.556026638419, 7406.629668516553, 7409.0, 7432.516886402421, 7449.925500744977, 7352.595607241599, 7445.664945077485, 7449.255074492562, 7465.738699145558, 7469.0, 34652.0, 34653.82673086618, 34660.0, 34773.30453390918, 35093.29813403743, 2626.6979297380853, 2645.1138868479084, 2649.205238428464, 2655.415808522122, 2697.460507898428, 21603.0, 21668.783312166834, 21766.89116554407, 21998.560028799333, 22409.775902240934, 2294.736105347873, 2308.3421224950807, 2350.97649023511, 2352.2943117064983, 2378.2865140457966, 121143.39428302801, 123944.0, 134186.329068355, 137829.0, 138537.30731346278, 39311.41032884509, 39389.01527461779, 39393.80303098466, 39602.60397396047, 39763.20473590512, 41760.37359439611, 41872.1625567493, 41883.0, 41967.790161049, 42026.78986605047, 12672.0, 12763.106582539318, 12821.102522823312, 12827.743445131046, 12875.54935577269, 10190.847137292947, 10215.182785377152, 10215.897841021495, 10219.744506387251, 10239.385636861813, 13224.0, 13315.134516256412, 13374.33128343579, 13374.732505349839, 13633.863661363259, 5573.303337082864, 5661.915071273934, 5665.915011274834, 5671.943280567141, 5703.287089113861, 798.6166640012826, 824.2993455562723, 833.2334252487726, 842.8440738463404, 844.7676888855614, 20674.896625517078, 21585.784142158693, 21649.89175054146, 21688.891555542123, 21702.348929532138, 911.480456140007, 918.2149262380568, 925.708401853414, 938.8544775559799, 947.0907928388687, 11034.393108378956, 11094.833577496343, 11306.378149201708, 11334.319940803578, 11384.373859437645, 229741.0, 230657.0, 231958.0, 232054.0, 232403.0, 44244.336334955, 44367.0, 44571.55428445674, 44596.55403445924, 44687.55312446899, 1211.4427363412929, 1241.1312081542776, 1245.9314737689515, 1247.65689435406, 1249.8062800265973, 19621.49856281028, 20257.59484810266, 20273.898630506752, 21107.472313192295, 21258.893705531373, 31305.0, 31366.0, 31390.215244619067, 31435.371292574477, 31474.370512590078, 209210.0, 210273.0, 211194.0, 211311.0, 211357.0, 29662.995495607818, 31578.210544736565, 31844.6815531851, 32019.199520012186, 32077.67922320747, 39929.60070399375, 40310.39534407045, 40631.593684062784, 41300.38049429201, 41560.79219603883, 30059.849700751358, 30185.245368865955, 30241.395172096876, 30511.694883050885, 30691.846540767154, 29529.557056644597, 29907.55138672878, 30322.393552128393, 31216.843915780275, 31232.0, 34183.65816341805, 34271.14322141966, 34585.30829383348, 35014.29971400608, 35033.82483087568, 29225.7077429223, 29397.85301073481, 29907.850460747555, 30152.547711783904, 30423.847880760455, 23334.0, 24410.389740256636, 24451.633225501988, 24576.87711561431, 24742.628860566747], "value": 14985.075019377693, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [415.45367841288703, 418.4079527468632, 421.41213007854043, 429.83881044608273, 432.76846886915496, 17797.288108475677, 18014.369497067604, 18847.811521884792, 18871.528211794695, 19055.42833714987, 17751.0, 17753.91123044386, 17767.378141765043, 17917.372891948784, 18085.64357673173, 5557.277553917985, 5639.830805075844, 5755.30936287644, 5787.47912687858, 5838.241028666267, 5791.536677065846, 5828.271466066742, 5843.18195452637, 5846.976779063647, 5847.473727364536, 5673.063944449161, 5699.800506982257, 5757.280339957505, 5763.366029736726, 5771.653700777967, 5762.135679648044, 5768.307803063638, 5808.273965754239, 5823.970880145594, 5830.212921255618, 5091.338126043628, 5172.974135129338, 5220.138677118309, 5221.164613661834, 5224.164133738622, 22000.66998995, 22297.44256393587, 22346.329610111723, 22348.553028939332, 22466.326010219556, 1478.8151481064867, 1503.1056521369767, 1521.3382178752279, 1568.6705791783625, 1578.423875285524, 13140.342982850914, 14010.29948502581, 14201.147931124166, 14225.0, 14579.0, 1697.236243690332, 1698.9405370812085, 1702.4637239269714, 1719.8968061916325, 1736.28812187004, 86492.0, 88312.0, 89536.55231723865, 90100.5494972521, 90826.0, 10467.947660261802, 10848.294860834021, 11056.336619802676, 11171.44142792857, 11243.325400475835, 15315.46395876139, 15394.692106157814, 15395.61510962235, 15418.845811541742, 15450.768238476432, 7476.364509016795, 7766.766996990099, 7882.369410447151, 7884.0144981877265, 7935.127136015034, 7036.859262814715, 7114.359707626235, 7242.348188663045, 7374.631268436557, 7407.296306850841, 6860.485463590251, 7015.228324884259, 7032.12098487689, 7058.54119482232, 7064.646767661597, 1984.2658216459713, 1994.2920263306607, 2035.3690355989395, 2052.399128679632, 2116.0426605389953, 827.7433995461427, 843.4011851585443, 848.3128665780737, 852.4953457148139, 879.683314006957, 15717.0, 16638.25127869249, 16698.4155554555, 16788.32846686119, 16818.327266909186, 872.4198408058696, 878.8022694893725, 893.1916615462886, 901.1079031758643, 901.8061116859907, 8694.869576956351, 8783.868241976375, 8836.381453298207, 9360.62557497706, 9405.247580193569, 429723.0, 430395.0, 447106.0, 448719.0, 449780.0, 80295.59852200818, 87729.56135219283, 87957.0, 89961.0, 90819.54590227139, 1032.2464600841429, 1043.7442826507554, 1061.045059446489, 1121.4560937945091, 1122.6351435783404, 17342.826571734626, 17349.82650173482, 17879.28482860671, 18037.0, 18181.181846817188, 42266.788666056476, 42588.78705606576, 43028.35457468078, 43237.0, 43660.126797463236, 55675.0, 56320.0, 56568.0, 57901.0, 58130.709346453, 8245.175482451707, 8306.460080094774, 8415.368847336353, 8474.110218426977, 8650.351223658377, 13195.802062969255, 13509.392077356346, 13544.932275338955, 13604.387802548712, 13649.658758531115, 10843.620473283398, 11192.944035279772, 11439.0, 11587.884121159017, 11858.873407026318, 10927.617533386294, 11079.889201107886, 11088.112950964065, 11128.262908933868, 11272.718182045515, 11654.0, 11795.056395488169, 11999.220050696676, 12069.75860482803, 12233.449494772934, 8298.170182981896, 8454.788630284293, 8570.100139485265, 8604.311655067704, 8690.826183476422, 10544.789104218025, 10558.736031599272, 10582.788344232918, 10680.359178449318, 11100.222984391015], "value": 9030.296997662253, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1201.8858208470194, 1257.076049103909, 1263.7977923532235, 1265.4242319744517, 1266.3225174531628, 9516.90483095169, 9630.566624501897, 9828.459434731096, 9848.409095454277, 9870.111689947904, 9863.21094312455, 10018.348807327518, 10087.848682269754, 10092.848607270897, 10101.595936162545, 2217.5897458970103, 2267.183813827021, 2270.920517781878, 2277.578647950126, 2278.134308962592, 2158.0720290275176, 2212.822974162071, 2215.667649852527, 2222.088943533152, 2243.517643706603, 2119.1205649655376, 2166.4042388343173, 2166.685830554569, 2184.5958497677902, 2186.9343919682433, 2111.767705552388, 2122.172352782416, 2122.5754849030177, 2122.8301735861096, 2128.829693624514, 1854.1934258597553, 1930.5077205312696, 2057.2902348689677, 2110.2508609443735, 2115.428834214769, 9549.713508594754, 9668.226541876633, 9670.516474176264, 9694.563744631509, 9697.515124243759, 5257.76340064698, 5450.781968721247, 5454.836354909359, 5455.263539422167, 5455.672659640394, 3363.4786608075788, 3364.596248450177, 3371.4605663094035, 3401.744869134825, 3453.8791142309897, 1012.5443550402348, 1023.8617786598788, 1039.9392619528114, 1084.6420681175193, 1094.9124070074374, 13980.999999999898, 13986.30068496571, 14024.08843425174, 14044.297785110704, 14076.788848167183, 4670.672588464101, 4799.760011999386, 4806.110869489155, 4847.80608775652, 4914.066327397783, 10414.531346089445, 10555.0, 10567.630132945464, 10568.15454763617, 10629.84055239172, 5403.2705584746345, 5420.620556561003, 5429.647072940246, 5431.348238211361, 5449.536789372948, 4727.503612120747, 4791.760411979387, 4947.332110165082, 4976.701397916137, 5000.449950505442, 3822.140018495816, 3903.9609603904164, 3933.9606603934167, 3963.365861462152, 4008.939865902014, 1153.682737247247, 1164.6913567904512, 1170.4030944218339, 1175.8471398718284, 1190.4880901212389, 678.6911955060435, 681.5731821817328, 688.4389222783416, 695.7495301691387, 702.7154002628944, 5227.947720522822, 5228.241904923841, 5327.9733601331745, 5376.1398176291605, 5423.457654234624, 535.8392482255385, 546.9343678758495, 548.3036543589619, 549.1488193300448, 558.6759679385947, 3187.856546455415, 3209.5988001499813, 3210.614726232867, 3257.918552036218, 3321.850516726753, 12133.27200367963, 12837.165584236995, 12848.678783030498, 12858.742825143632, 12863.678408039874, 12617.36913154357, 12674.873251267554, 12692.746145077046, 12734.872651273368, 12746.980241580837, 9085.954570227106, 9094.909050909539, 9112.635494580276, 9219.400738951947, 9333.953330233306, 5405.216243644728, 5416.431274716256, 5423.3491980962535, 5615.073512870331, 5752.252207213036, 11137.777244455228, 11211.103111750877, 11249.493772780408, 11263.718407039889, 11423.485943132413, 11361.261518001635, 11473.368964706688, 11476.885231147582, 11574.88425115738, 11638.476268567765, 7677.5777332245025, 7896.881546776688, 7969.721059763112, 7971.481853679492, 7976.082750483537, 8873.112688731075, 8904.421212621159, 8990.775230619287, 8996.59515321799, 9007.279417646703, 7210.89183662256, 7282.96358518204, 7292.380147687506, 7320.926790732237, 7340.302671246223, 7154.284571542804, 7323.560586364835, 7328.81677958034, 7391.260873912781, 7505.249475052451, 6465.3534646536855, 6535.477161827138, 6548.0832683423405, 6606.075149479173, 6610.10763546915, 4754.405699287589, 4818.725783266648, 4825.686330388514, 4867.878303042452, 5015.1975683890405, 5118.872028199325, 5164.302819119521, 5302.522772950453, 5354.223637572452, 5432.456754324536], "value": 4525.214576779427, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [386.6578078400618, 388.1751278533117, 391.7199202570163, 392.3545767212935, 395.5767328958015, 5480.6437581557175, 5567.164925261208, 5568.749406276717, 5622.678670512426, 5698.886022279562, 5402.78388864445, 5472.20653005314, 5489.752961116749, 5496.065668836298, 5502.174673798932, 4153.626173644371, 4244.172386384648, 4269.87190384289, 4273.166732487166, 4309.806058727349, 4268.286590666109, 4280.678949078816, 4284.550122237167, 4286.699931004831, 4301.526832048487, 4243.215005224043, 4251.638610718092, 4271.252530807112, 4278.1871444425615, 4282.635975942048, 4086.4255519023122, 4201.327787553977, 4221.936670949938, 4239.342901850218, 4284.550122237167, 3669.321175582526, 3674.889753307405, 3694.704423646102, 3697.371446854041, 3698.130939229279, 5372.167314066326, 5390.299261096072, 5390.838274851761, 5391.514763671288, 5415.48552887475, 4714.599259062984, 4844.868257966484, 4885.2427873679635, 4891.144049791273, 4898.755062246873, 5807.070868661036, 5810.099434587646, 5818.330891947396, 5821.941780582225, 5825.359210486843, 4059.4316795648633, 4059.6955228357697, 4061.1471590965934, 4064.552899181088, 4070.5725898780793, 5982.341942386334, 6095.6952152392205, 6125.203723515959, 6141.570090093695, 6143.232095988046, 4142.523609784853, 4166.645835103989, 4169.249535083714, 4173.373993900878, 4196.244675958357, 4214.726042807208, 4217.662586993033, 4230.534641189467, 4241.409471448207, 4266.850660226877, 4768.451628062748, 4881.731504767272, 4894.681845680019, 4895.069936712013, 4911.484294149063, 5828.271466066742, 5836.824895253149, 5852.15143804146, 5854.531637468992, 5862.895972819044, 1454.5200083972263, 1468.6255004973877, 1471.0732238689557, 1471.4261438039173, 1475.697482016184, 900.0255689082124, 961.1541843177959, 965.2133511188359, 974.3374505336297, 983.9360441571275, 5053.1157047516535, 5072.340595722532, 5080.618953578422, 5082.186850103965, 5091.618128640367, 6033.547483938634, 6079.787207447806, 6118.816435506942, 6120.571559990758, 6123.785667501616, 4862.322498738001, 5041.294218809369, 5080.060188865072, 5089.185730283211, 5096.413912400048, 3192.393445245396, 3232.143481977278, 3242.8702851886133, 3250.756193285513, 3262.429074911881, 5520.475554822286, 5675.489205971482, 5678.4605462480995, 5680.573956953245, 5683.744231509592, 6104.236970378703, 6162.661053642093, 6174.722137503733, 6177.62934223948, 6186.19579454668, 5475.206095116186, 5653.547716182696, 5654.37801841797, 5666.46168613981, 5687.744051517609, 6063.151158837678, 6092.055731361646, 6092.177556029879, 6096.207493025879, 6110.7861224856915, 5769.105788602773, 5963.91054134197, 5975.940240597538, 5978.611390259794, 5978.641281523122, 5618.2415373924, 5638.182463542846, 5640.041192997282, 5645.266115404971, 5660.971695141498, 4180.498340199196, 4183.539810620769, 4188.2879910414695, 4190.790460477025, 4191.664666826708, 5761.942380576141, 5970.641761494324, 6055.333913269449, 6056.788012419721, 6072.271327440735, 5765.740541675551, 5801.912971305349, 5805.29770885584, 5830.708464576669, 5840.532757379315, 5640.294963129609, 5818.796342128006, 5854.336276993872, 5875.030619947661, 5875.7084736953475, 5755.5683323750045, 5758.798442054508, 5762.0, 5778.942210578009, 5796.739146738491, 5877.882442351214, 5878.470937615464, 5904.793332233351, 5906.0, 5915.0, 5471.714147175331, 5699.0, 5731.684757338387, 5738.368779434176, 5763.827085187451], "value": 4382.371551565324, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [593.5874567175812, 594.0702800117813, 614.9354317796631, 626.7962912053583, 632.8354627796773, 11941.940290298548, 12251.816222756666, 12293.0, 12711.682207944783, 14098.365573549188, 13203.141795783267, 13537.458501659921, 13595.18428894267, 13601.31993400331, 13671.453141874337, 855.0423525651264, 877.1623099939543, 878.8066625342415, 881.2861582118504, 881.9647214111428, 879.8548239540468, 894.2756367342473, 896.860986547083, 912.3385545479507, 919.0304229038362, 962.7304354780638, 962.8796400449944, 966.3042609321277, 966.5602151021303, 968.239931653652, 947.0907928388755, 949.952502374882, 953.3755390219418, 953.4803532075001, 953.5851904421564, 702.5995182745837, 706.5054461876707, 707.0419581467141, 708.1572928215459, 708.3518580498837, 13284.601461956061, 13437.798433023512, 13465.778146605799, 13615.727685446236, 14519.0, 12633.93683031588, 12641.747165056739, 12686.302253376061, 12797.360131993362, 13188.032138146056, 8286.254237118688, 8532.36007299455, 8599.226069653698, 8637.870431943464, 8676.305895528343, 2327.5461285049423, 2328.1851352026824, 2329.94175145621, 2393.628987506939, 2426.0295881647307, 62105.68947155235, 62412.37587624157, 62455.37544624587, 63054.99999999954, 63424.04863927045, 9740.951295243478, 9762.36544624597, 9807.215422766161, 10045.397276163454, 10216.506107855854, 21408.146533623785, 21480.462988425414, 21619.67570486444, 21670.674939875913, 21681.0, 5890.646561206341, 6055.72749226277, 6389.680515974275, 6474.546781725234, 6480.384363485461, 2855.8714857831446, 2926.2099233206914, 2937.2803663102677, 2952.232419570927, 3028.3337665713507, 3256.1534001159393, 3291.983540082332, 3318.900432987014, 3322.2691007978688, 3331.6501767314567, 640.5900223856736, 642.408983734965, 642.6626021338741, 658.3285049249738, 658.9110470086573, 466.6756604160076, 468.08255818595933, 471.25071136892507, 488.2578480709373, 493.6519753573722, 6829.863402731918, 7064.004463573994, 7065.116860392451, 7379.3358597726465, 7504.6247687615405, 458.8164734106317, 461.7898856020503, 479.9304100905419, 485.37895365028425, 501.47846239910086, 3115.0, 3302.6036875575087, 3368.2926585417335, 3380.678835510623, 3493.318802833423, 60487.39512604906, 60843.695781521696, 61303.0, 61457.38542614606, 62907.0, 38343.808280958416, 42129.0, 49731.751341243784, 53691.731541342044, 53967.0, 44951.55048449539, 45810.0, 46122.308165377544, 46255.76872115618, 46273.30590041085, 7671.968151478549, 8006.639701213343, 8058.274755272054, 8064.838703225785, 8235.588220588827, 23066.0, 24332.87833560821, 24482.0, 25040.62439063452, 25183.37041573975, 39927.401088983104, 41010.589894100674, 41057.794711026254, 41399.37900931428, 41712.0, 10053.69838904834, 10132.960111966528, 10217.948910255402, 10218.284720069523, 10269.075783179549, 11041.171912106463, 11055.889441105486, 11199.160062995144, 11268.38023908693, 11282.717932051764, 10047.598096076075, 10065.446400448045, 10192.949035254776, 10378.844317335095, 10424.322419042737, 11048.944755276172, 11150.888491115309, 11191.351866466044, 11375.829362559403, 11675.591354302898, 10250.016208910376, 10336.483175841327, 10448.52981615814, 10524.894751052392, 10811.675649730521, 7686.807829804299, 7881.605919704107, 7957.56233407145, 7993.480423772437, 8057.5165490070785, 8141.8371632568205, 8405.915940840514, 8572.099929507312, 8727.956360218412, 8948.373613847229], "value": 5896.196136274507, "unit": "score"}]}, {"extraOptions": ["e10s"], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1589.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [104.0, 114.0, 113.0, 113.0, 113.0, 114.0, 117.0, 113.0, 112.0, 114.0], "value": 112.7, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 130.0, 128.0, 172.0, 133.0, 137.0, 136.0, 128.0, 151.0, 132.0], "value": 139.9, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [168.0, 166.0, 168.0, 174.0, 176.0, 167.0, 167.0, 169.0, 169.0, 163.0], "value": 168.7, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [77.0, 77.0, 76.0, 121.0, 76.0, 79.0, 79.0, 76.0, 93.0, 75.0], "value": 82.9, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 86.0, 110.0, 85.0, 86.0, 85.0, 99.0, 84.0, 86.0, 112.0], "value": 91.7, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [115.0, 114.0, 116.0, 115.0, 114.0, 120.0, 114.0, 114.0, 114.0, 115.0], "value": 115.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [152.0, 151.0, 151.0, 151.0, 152.0, 152.0, 153.0, 152.0, 151.0, 152.0], "value": 151.7, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [94.0, 95.0, 95.0, 94.0, 96.0, 96.0, 95.0, 95.0, 95.0, 95.0], "value": 95.0, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [68.0, 68.0, 68.0, 69.0, 69.0, 70.0, 70.0, 69.0, 70.0, 68.0], "value": 68.9, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [56.0, 57.0, 59.0, 57.0, 56.0, 56.0, 57.0, 56.0, 56.0, 56.0], "value": 56.6, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 83.0, 83.0, 92.0, 84.0, 83.0, 86.0, 92.0, 92.0, 84.0], "value": 86.3, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [144.0, 160.0, 140.0, 144.0, 135.0, 143.0, 137.0, 152.0, 192.0, 142.0], "value": 148.9, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [196.0, 198.0, 196.0, 203.0, 212.0, 192.0, 198.0, 201.0, 196.0, 193.0], "value": 198.5, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [72.0, 74.0, 72.0, 75.0, 74.0, 73.0, 72.0, 71.0, 72.0, 75.0], "value": 73.0, "unit": "score"}]}]} 04:51:10 INFO - Return code: 0 04:51:10 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 04:51:10 ERROR - # TBPL SUCCESS # 04:51:10 INFO - Running post-action listener: _resource_record_post_action 04:51:10 INFO - [mozharness: 2017-03-31 11:51:10.884371Z] Finished run-tests step (success) 04:51:10 INFO - Running post-run listener: _resource_record_post_run 04:51:10 INFO - Total resource usage - Wall time: 1203s; CPU: 13.0%; Read bytes: 0; Write bytes: 363687936; Read time: 0; Write time: 205972 04:51:10 INFO - TinderboxPrint: CPU usage
13.3% 04:51:10 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 04:51:10 INFO - TinderboxPrint: I/O write bytes / time
363,687,936 / 205,972 04:51:10 INFO - TinderboxPrint: CPU idle
8,319.5 (86.7%) 04:51:10 INFO - TinderboxPrint: CPU user
1,221.1 (12.7%) 04:51:10 INFO - TinderboxPrint: Swap in / out
0 / 0 04:51:10 INFO - install - Wall time: 16s; CPU: 13.0%; Read bytes: 0; Write bytes: 106590208; Read time: 0; Write time: 93884 04:51:10 INFO - run-tests - Wall time: 1188s; CPU: 13.0%; Read bytes: 0; Write bytes: 257097728; Read time: 0; Write time: 112088 04:51:11 INFO - Running post-run listener: _upload_blobber_files 04:51:11 INFO - Blob upload gear active. 04:51:11 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 04:51:11 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 04:51:11 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', 'Firefox-Non-PGO', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 04:51:11 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 Firefox-Non-PGO -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 04:51:11 INFO - (blobuploader) - INFO - Open directory for files ... 04:51:11 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 04:51:11 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:11 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:15 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 04:51:15 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:15 INFO - (blobuploader) - INFO - Done attempting. 04:51:15 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_raw.log ... 04:51:15 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:15 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:16 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_raw.log: uploaded 04:51:16 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:16 INFO - (blobuploader) - INFO - Done attempting. 04:51:16 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 04:51:16 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:16 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:17 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 04:51:17 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:17 INFO - (blobuploader) - INFO - Done attempting. 04:51:17 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs-e10s_errorsummary.log ... 04:51:17 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 04:51:17 INFO - (blobuploader) - INFO - Uploading, attempt #1. 04:51:18 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs-e10s_errorsummary.log: uploaded 04:51:18 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 04:51:18 INFO - (blobuploader) - INFO - Done attempting. 04:51:18 INFO - (blobuploader) - INFO - Iteration through files over. 04:51:18 INFO - Return code: 0 04:51:18 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 04:51:18 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 04:51:18 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c4420641e24bdabcc73571e969c18e6906bff858593236b1f98cd1a49dffd5151e974c7650254ff32e180e22ce1802f8c28c4dd5fb7558b553a2620bbbd4362", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dee1290ccd7ecbdcb998b6b048fa7b0395bf77dca980c2f728a8d03f26a0a2947c469f78f2e0d7416b5581164ded30d195ece7d76a3a27b9f0761c72289ade9b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45f6b73e3d78655aeddbd01d2f94b57135359247c0b79a67cc723981d8181ee010cddb3ea3b1fcee63bfaf251fdb3f6e014c098f6af0bb6731ac6cf3ba2b3b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae4046e42c6eabc88c2b85b31722f1413f82778f60ded56988db0161e9d0b7ca565eceb7d2ceabb0c2c718a84dc540dae72ac6d99cc4b9d066c29a98bd7577cb"} 04:51:18 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 04:51:18 INFO - Writing to file /builds/slave/test/properties/blobber_files 04:51:18 INFO - Contents: 04:51:18 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c4420641e24bdabcc73571e969c18e6906bff858593236b1f98cd1a49dffd5151e974c7650254ff32e180e22ce1802f8c28c4dd5fb7558b553a2620bbbd4362", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dee1290ccd7ecbdcb998b6b048fa7b0395bf77dca980c2f728a8d03f26a0a2947c469f78f2e0d7416b5581164ded30d195ece7d76a3a27b9f0761c72289ade9b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45f6b73e3d78655aeddbd01d2f94b57135359247c0b79a67cc723981d8181ee010cddb3ea3b1fcee63bfaf251fdb3f6e014c098f6af0bb6731ac6cf3ba2b3b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae4046e42c6eabc88c2b85b31722f1413f82778f60ded56988db0161e9d0b7ca565eceb7d2ceabb0c2c718a84dc540dae72ac6d99cc4b9d066c29a98bd7577cb"} 04:51:18 INFO - Running post-run listener: copy_logs_to_upload_dir 04:51:18 INFO - Copying logs to upload dir... 04:51:18 INFO - mkdir: /builds/slave/test/build/upload/logs 04:51:18 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1261.747751 ========= master_lag: -0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 21 mins, 1 secs) (at 2017-03-31 04:51:17.651848) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:17.657800) ========= 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=0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c4420641e24bdabcc73571e969c18e6906bff858593236b1f98cd1a49dffd5151e974c7650254ff32e180e22ce1802f8c28c4dd5fb7558b553a2620bbbd4362", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dee1290ccd7ecbdcb998b6b048fa7b0395bf77dca980c2f728a8d03f26a0a2947c469f78f2e0d7416b5581164ded30d195ece7d76a3a27b9f0761c72289ade9b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45f6b73e3d78655aeddbd01d2f94b57135359247c0b79a67cc723981d8181ee010cddb3ea3b1fcee63bfaf251fdb3f6e014c098f6af0bb6731ac6cf3ba2b3b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae4046e42c6eabc88c2b85b31722f1413f82778f60ded56988db0161e9d0b7ca565eceb7d2ceabb0c2c718a84dc540dae72ac6d99cc4b9d066c29a98bd7577cb"} build_url:https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011530 build_url: 'https://queue.taskcluster.net/v1/task/FziPe46tTL-TfFHS0Z7sXw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/7c4420641e24bdabcc73571e969c18e6906bff858593236b1f98cd1a49dffd5151e974c7650254ff32e180e22ce1802f8c28c4dd5fb7558b553a2620bbbd4362", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/dee1290ccd7ecbdcb998b6b048fa7b0395bf77dca980c2f728a8d03f26a0a2947c469f78f2e0d7416b5581164ded30d195ece7d76a3a27b9f0761c72289ade9b", "dromaeojs-e10s_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/45f6b73e3d78655aeddbd01d2f94b57135359247c0b79a67cc723981d8181ee010cddb3ea3b1fcee63bfaf251fdb3f6e014c098f6af0bb6731ac6cf3ba2b3b86", "dromaeojs-e10s_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox-Non-PGO/sha512/ae4046e42c6eabc88c2b85b31722f1413f82778f60ded56988db0161e9d0b7ca565eceb7d2ceabb0c2c718a84dc540dae72ac6d99cc4b9d066c29a98bd7577cb"}' ========= master_lag: 0.02 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:17.687753) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:17.688496) ========= 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=0a54c299e5ca1d63338479f2000001b5-1490959813.971040-1931484725 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004427 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-03-31 04:51:17.770533) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 04:51:17.770834) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-03-31 04:51:17.771116) ========= ========= Total master_lag: 0.15 =========