builder: mozilla-central_ubuntu64_hw_test-dromaeojs-pgo slave: talos-linux64-ix-084 starttime: 1487367867.11 results: success (0) revision: f302def88fe5040923ffc2295b7c804b693e7b79 ========= Started set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.105912) ========= master: http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/ ========= Finished set props: master (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.106411) ========= ========= Started downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.106693) ========= ========= Finished downloading to buildprops.json (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.123313) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.123697) ========= 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=ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False --2017-02-17 13:44:27-- 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% 37.5M=0s 2017-02-17 13:44:27 (37.5 MB/s) - `archiver_client.py' saved [12179/12179] program finished with exit code 0 elapsedTime=0.133917 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.271072) ========= ========= Started 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.271395) ========= 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=ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.020218 ========= master_lag: 0.03 ========= ========= Finished 'rm -rf ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:27.322822) ========= ========= Started 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 13:44:27.323485) ========= bash -c 'python archiver_client.py mozharness --repo mozilla-central --rev f302def88fe5040923ffc2295b7c804b693e7b79 --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 f302def88fe5040923ffc2295b7c804b693e7b79 --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=ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 2017-02-17 13:44:28,047 truncating revision to first 12 chars 2017-02-17 13:44:28,048 Setting DEBUG logging. 2017-02-17 13:44:28,048 attempt 1/10 2017-02-17 13:44:28,048 Getting archive location from https://api.pub.build.mozilla.org/archiver/hgmo/mozilla-central/f302def88fe5?&preferred_region=us-west-2&suffix=tar.gz&subdir=testing/mozharness 2017-02-17 13:44:29,428 unpacking tar archive at: mozilla-central-f302def88fe5/testing/mozharness/ program finished with exit code 0 elapsedTime=1.792081 ========= master_lag: 0.01 ========= ========= Finished 'bash -c ...' (results: 0, elapsed: 1 secs) (at 2017-02-17 13:44:29.130072) ========= ========= Started set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:29.130459) ========= script_repo_revision: f302def88fe5040923ffc2295b7c804b693e7b79 ========= Finished set props: script_repo_revision (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:29.130912) ========= ========= Started downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:29.131221) ========= ========= Finished downloading to oauth.txt (results: 0, elapsed: 0 secs) (at 2017-02-17 13:44:29.140596) ========= ========= Started '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-17 13:44:29.140991) ========= /tools/buildbot/bin/python scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox in dir /builds/slave/test/. (timeout 3600 secs) (maxTime 7200 secs) watching logfiles {} argv: ['/tools/buildbot/bin/python', 'scripts/scripts/talos_script.py', '--suite', 'dromaeojs', '--add-option', '--webServer,localhost', '--branch-name', 'Firefox', '--cfg', 'talos/linux_config.py', '--download-symbols', 'ondemand', '--use-talos-json', '--blob-upload-branch', 'Firefox'] 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=ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False 13:44:29 INFO - MultiFileLogger online at 20170217 13:44:29 in /builds/slave/test 13:44:29 INFO - Run as scripts/scripts/talos_script.py --suite dromaeojs --add-option --webServer,localhost --branch-name Firefox --cfg talos/linux_config.py --download-symbols ondemand --use-talos-json --blob-upload-branch Firefox 13:44:29 INFO - Dumping config to /builds/slave/test/logs/localconfig.json. 13:44:29 INFO - {'append_to_log': False, 13:44:29 INFO - 'base_work_dir': '/builds/slave/test', 13:44:29 INFO - 'blob_upload_branch': 'Firefox', 13:44:29 INFO - 'blob_uploader_auth_file': '/builds/slave/test/oauth.txt', 13:44:29 INFO - 'branch': 'Firefox', 13:44:29 INFO - 'buildbot_json_path': 'buildprops.json', 13:44:29 INFO - 'config_files': ('talos/linux_config.py',), 13:44:29 INFO - 'default_actions': ('clobber', 13:44:29 INFO - 'read-buildbot-config', 13:44:29 INFO - 'download-and-extract', 13:44:29 INFO - 'populate-webroot', 13:44:29 INFO - 'create-virtualenv', 13:44:29 INFO - 'install', 13:44:29 INFO - 'run-tests'), 13:44:29 INFO - 'default_blob_upload_servers': ('https://blobupload.elasticbeanstalk.com',), 13:44:29 INFO - 'download_minidump_stackwalk': True, 13:44:29 INFO - 'download_symbols': 'ondemand', 13:44:29 INFO - 'exes': {'python': '/tools/buildbot/bin/python', 13:44:29 INFO - 'tooltool.py': '/tools/tooltool.py', 13:44:29 INFO - 'virtualenv': ('/tools/buildbot/bin/python', 13:44:29 INFO - '/tools/misc-python/virtualenv.py')}, 13:44:29 INFO - 'find_links': ('http://pypi.pvt.build.mozilla.org/pub', 13:44:29 INFO - 'http://pypi.pub.build.mozilla.org/pub'), 13:44:29 INFO - 'installer_path': 'installer.exe', 13:44:29 INFO - 'log_level': 'info', 13:44:29 INFO - 'log_name': 'talos', 13:44:29 INFO - 'log_to_console': True, 13:44:29 INFO - 'minidump_stackwalk_path': 'linux64-minidump_stackwalk', 13:44:29 INFO - 'minidump_tooltool_manifest_path': 'config/tooltool-manifests/linux64/releng.manifest', 13:44:29 INFO - 'opt_config_files': (), 13:44:29 INFO - 'pip_index': False, 13:44:29 INFO - 'sps_profile': False, 13:44:29 INFO - 'sps_profile_interval': 0, 13:44:29 INFO - 'suite': 'dromaeojs', 13:44:29 INFO - 'system_bits': '32', 13:44:29 INFO - 'talos_extra_options': ('--webServer', 'localhost'), 13:44:29 INFO - 'title': 'talos-linux64-ix-084', 13:44:29 INFO - 'tooltool_cache': '/builds/tooltool_cache', 13:44:29 INFO - 'use_talos_json': True, 13:44:29 INFO - 'virtualenv_path': '/builds/slave/test/build/venv', 13:44:29 INFO - 'volatile_config': {'actions': None, 'add_actions': None, 'no_actions': None}, 13:44:29 INFO - 'work_dir': 'build'} 13:44:29 INFO - [mozharness: 2017-02-17 21:44:29.952799Z] Running clobber step. 13:44:29 INFO - Running pre-action listener: _resource_record_pre_action 13:44:29 INFO - Running main action method: clobber 13:44:29 INFO - rmtree: /builds/slave/test/build 13:44:29 INFO - retry: Calling rmtree with args: ('/builds/slave/test/build',), kwargs: {}, attempt #1 13:44:31 INFO - Running post-action listener: _resource_record_post_action 13:44:31 INFO - [mozharness: 2017-02-17 21:44:31.399789Z] Finished clobber step (success) 13:44:31 INFO - [mozharness: 2017-02-17 21:44:31.399888Z] Running read-buildbot-config step. 13:44:31 INFO - Running pre-action listener: _resource_record_pre_action 13:44:31 INFO - Running main action method: read_buildbot_config 13:44:31 INFO - Using buildbot properties: 13:44:31 INFO - { 13:44:31 INFO - "project": "", 13:44:31 INFO - "product": "firefox", 13:44:31 INFO - "who": "kwierso@gmail.com", 13:44:31 INFO - "installer_path": "public/build/target.tar.bz2", 13:44:31 INFO - "builddir": "mozilla-central_ubuntu64_hw_test-dromaeojs", 13:44:31 INFO - "repository": "https://hg.mozilla.org/mozilla-central/", 13:44:31 INFO - "buildername": "Ubuntu HW 12.04 x64 mozilla-central pgo talos dromaeojs", 13:44:31 INFO - "stage_platform": "linux64-pgo", 13:44:31 INFO - "basedir": "/builds/slave/test", 13:44:31 INFO - "buildnumber": 39, 13:44:31 INFO - "platform": "ubuntu64_hw", 13:44:31 INFO - "master": "http://buildbot-master104.bb.releng.scl3.mozilla.com:8201/", 13:44:31 INFO - "slavebuilddir": "test", 13:44:31 INFO - "taskId": "fHEKRcUmSqqRbQWDJxkhEg", 13:44:31 INFO - "branch": "mozilla-central", 13:44:31 INFO - "script_repo_revision": "production", 13:44:31 INFO - "revision": "f302def88fe5040923ffc2295b7c804b693e7b79", 13:44:31 INFO - "slavename": "talos-linux64-ix-084", 13:44:31 INFO - "repo_path": "mozilla-central" 13:44:31 INFO - } 13:44:31 INFO - Finding installer, test and symbols from parent task. 13:44:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fHEKRcUmSqqRbQWDJxkhEg'}, attempt #1 13:44:31 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/fHEKRcUmSqqRbQWDJxkhEg'}, attempt #1 13:44:32 INFO - Task dependencies: Acd_kqAOQcCeZfTrp5-fiw 13:44:32 INFO - retry: Calling _urlopen with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw'}, attempt #1 13:44:32 INFO - Set installer_url: https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2 13:44:32 INFO - Set test_url: https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/test_packages.json 13:44:32 INFO - Set symbols_url: https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.crashreporter-symbols.zip 13:44:32 INFO - Running post-action listener: _resource_record_post_action 13:44:32 INFO - [mozharness: 2017-02-17 21:44:32.796785Z] Finished read-buildbot-config step (success) 13:44:32 INFO - [mozharness: 2017-02-17 21:44:32.797003Z] Running download-and-extract step. 13:44:32 INFO - Running pre-action listener: _resource_record_pre_action 13:44:32 INFO - Running main action method: download_and_extract 13:44:32 INFO - mkdir: /builds/slave/test/build/tests 13:44:32 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')]} 13:44:32 INFO - trying https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.test_packages.json 13:44:32 INFO - Downloading https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.test_packages.json to /builds/slave/test/build/target.test_packages.json 13:44:32 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.test_packages.json', 'file_name': '/builds/slave/test/build/target.test_packages.json'}, attempt #1 13:44:33 INFO - Downloaded 896 bytes. 13:44:33 INFO - Reading from file /builds/slave/test/build/target.test_packages.json 13:44:33 INFO - Using the following test package requirements: 13:44:33 INFO - {u'common': [u'target.common.tests.zip'], 13:44:33 INFO - u'cppunittest': [u'target.common.tests.zip', u'target.cppunittest.tests.zip'], 13:44:33 INFO - u'gtest': [u'target.common.tests.zip', u'target.gtest.tests.zip'], 13:44:33 INFO - u'jittest': [u'target.common.tests.zip', u'target.jsshell.zip'], 13:44:33 INFO - u'mochitest': [u'target.common.tests.zip', u'target.mochitest.tests.zip'], 13:44:33 INFO - u'mozbase': [u'target.common.tests.zip'], 13:44:33 INFO - u'reftest': [u'target.common.tests.zip', u'target.reftest.tests.zip'], 13:44:33 INFO - u'talos': [u'target.common.tests.zip', u'target.talos.tests.zip'], 13:44:33 INFO - u'web-platform': [u'target.common.tests.zip', 13:44:33 INFO - u'target.web-platform.tests.zip'], 13:44:33 INFO - u'xpcshell': [u'target.common.tests.zip', u'target.xpcshell.tests.zip']} 13:44:33 INFO - Downloading packages: [u'target.common.tests.zip'] for test suite category: common 13:44:33 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.common.tests.zip 13:44:33 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:44:33 INFO - Fetch https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.common.tests.zip into memory 13:44:34 INFO - Expected file size: 59220974 13:44:34 INFO - Obtained file size: 59220974 13:44:44 INFO - Downloading packages: [u'target.common.tests.zip', u'target.talos.tests.zip'] for test suite category: talos 13:44:44 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.common.tests.zip 13:44:44 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.common.tests.zip'}, attempt #1 13:44:44 INFO - Fetch https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.common.tests.zip into memory 13:44:45 INFO - Expected file size: 59220974 13:44:45 INFO - Obtained file size: 59220974 13:44:56 INFO - Downloading and extracting to /builds/slave/test/build/tests these dirs * from https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.talos.tests.zip 13:44:56 INFO - retry: Calling fetch_url_into_memory with args: (), kwargs: {'url': u'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.talos.tests.zip'}, attempt #1 13:44:56 INFO - Fetch https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.talos.tests.zip into memory 13:44:57 INFO - Expected file size: 14403647 13:44:57 INFO - Obtained file size: 14403647 13:44:57 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:44:57 INFO - trying https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2 13:44:57 INFO - Downloading https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2 to /builds/slave/test/build/target.tar.bz2 13:44:57 INFO - retry: Calling _download_file with args: (), kwargs: {'url': 'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2', 'file_name': '/builds/slave/test/build/target.tar.bz2'}, attempt #1 13:44:58 INFO - Downloaded 66761806 bytes. 13:44:58 INFO - Setting buildbot property build_url to https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2 13:44:58 INFO - mkdir: /builds/slave/test/properties 13:44:58 INFO - Writing buildbot properties ['build_url'] to /builds/slave/test/properties/build_url 13:44:58 INFO - Writing to file /builds/slave/test/properties/build_url 13:44:58 INFO - Contents: 13:44:58 INFO - build_url:https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2 13:44:58 INFO - Running post-action listener: _resource_record_post_action 13:44:58 INFO - Running post-action listener: set_extra_try_arguments 13:44:58 INFO - [mozharness: 2017-02-17 21:44:58.736124Z] Finished download-and-extract step (success) 13:44:58 INFO - [mozharness: 2017-02-17 21:44:58.736221Z] Running populate-webroot step. 13:44:58 INFO - Running pre-action listener: _resource_record_pre_action 13:44:58 INFO - Running main action method: populate_webroot 13:44:58 INFO - {'extra_options': {'android': ['--apkPath=%(apk_path)s']}, 13:44:58 INFO - 'mobile-suites': {'remote-tp4m_nochrome': {'tests': ['tp4m']}, 13:44:58 INFO - 'remote-tsvgx': {'talos_options': ['--noChrome', 13:44:58 INFO - '--tppagecycles', 13:44:58 INFO - '7'], 13:44:58 INFO - 'tests': ['tsvgm']}}, 13:44:58 INFO - 'suites': {'chromez': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['tresize', 'tcanvasmark']}, 13:44:58 INFO - 'chromez-e10s': {'tests': ['tresize', 'tcanvasmark']}, 13:44:58 INFO - 'dromaeojs': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['dromaeo_css', 'kraken']}, 13:44:58 INFO - 'dromaeojs-e10s': {'tests': ['dromaeo_css', 'kraken']}, 13:44:58 INFO - 'g1': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:44:58 INFO - 'g1-e10s': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'tests': ['tp5o_scroll', 'glterrain']}, 13:44:58 INFO - 'g2': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['damp', 'tps']}, 13:44:58 INFO - 'g2-e10s': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'tests': ['damp', 'tps']}, 13:44:58 INFO - 'g3': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['dromaeo_dom']}, 13:44:58 INFO - 'g3-e10s': {'tests': ['dromaeo_dom']}, 13:44:58 INFO - 'g4': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['basic_compositor_video']}, 13:44:58 INFO - 'g4-e10s': {'tests': ['basic_compositor_video']}, 13:44:58 INFO - 'other': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['a11yr', 13:44:58 INFO - 'ts_paint', 13:44:58 INFO - 'tpaint', 13:44:58 INFO - 'sessionrestore', 13:44:58 INFO - 'sessionrestore_no_auto_restore', 13:44:58 INFO - 'tabpaint']}, 13:44:58 INFO - 'other-e10s': {'tests': ['a11yr', 13:44:58 INFO - 'ts_paint', 13:44:58 INFO - 'tpaint', 13:44:58 INFO - 'sessionrestore', 13:44:58 INFO - 'sessionrestore_no_auto_restore', 13:44:58 INFO - 'tabpaint']}, 13:44:58 INFO - 'other-e10s_l64': {'tests': ['a11yr', 13:44:58 INFO - 'ts_paint', 13:44:58 INFO - 'tpaint', 13:44:58 INFO - 'sessionrestore', 13:44:58 INFO - 'sessionrestore_no_auto_restore', 13:44:58 INFO - 'tabpaint']}, 13:44:58 INFO - 'other-e10s_nol64': {'tests': ['a11yr', 13:44:58 INFO - 'ts_paint', 13:44:58 INFO - 'tpaint', 13:44:58 INFO - 'sessionrestore', 13:44:58 INFO - 'sessionrestore_no_auto_restore', 13:44:58 INFO - 'tabpaint']}, 13:44:58 INFO - 'other_l64': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['a11yr', 13:44:58 INFO - 'ts_paint', 13:44:58 INFO - 'tpaint', 13:44:58 INFO - 'sessionrestore', 13:44:58 INFO - 'sessionrestore_no_auto_restore', 13:44:58 INFO - 'tabpaint']}, 13:44:58 INFO - 'other_nol64': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['a11yr', 13:44:58 INFO - 'ts_paint', 13:44:58 INFO - 'tpaint', 13:44:58 INFO - 'sessionrestore', 13:44:58 INFO - 'sessionrestore_no_auto_restore', 13:44:58 INFO - 'tabpaint']}, 13:44:58 INFO - 'svgr': {'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['tsvgx', 13:44:58 INFO - 'tsvgr_opacity', 13:44:58 INFO - 'tart', 13:44:58 INFO - 'tscrollx', 13:44:58 INFO - 'cart', 13:44:58 INFO - 'tsvg_static']}, 13:44:58 INFO - 'svgr-e10s': {'tests': ['tsvgx', 13:44:58 INFO - 'tsvgr_opacity', 13:44:58 INFO - 'tart', 13:44:58 INFO - 'tscrollx', 13:44:58 INFO - 'cart', 13:44:58 INFO - 'tsvg_static']}, 13:44:58 INFO - 'tp5o': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'talos_options': ['--disable-e10s'], 13:44:58 INFO - 'tests': ['tp5o']}, 13:44:58 INFO - 'tp5o-e10s': {'pagesets_name': 'tp5n.zip', 'tests': ['tp5o']}, 13:44:58 INFO - 'xperf': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'talos_options': ['--disable-e10s', 13:44:58 INFO - '--xperf_path', 13:44:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:44:58 INFO - 'tests': ['tp5n']}, 13:44:58 INFO - 'xperf-e10s': {'pagesets_name': 'tp5n.zip', 13:44:58 INFO - 'talos_options': ['--xperf_path', 13:44:58 INFO - '"c:/Program Files/Microsoft Windows Performance Toolkit/xperf.exe"'], 13:44:58 INFO - 'tests': ['tp5n']}}, 13:44:58 INFO - 'talos.zip': {'path': '', 13:44:58 INFO - 'url': 'http://talos-bundles.pvt.build.mozilla.org/zips/talos.a6052c33d420.zip'}} 13:44:58 INFO - Running post-action listener: _resource_record_post_action 13:44:58 INFO - [mozharness: 2017-02-17 21:44:58.749312Z] Finished populate-webroot step (success) 13:44:58 INFO - [mozharness: 2017-02-17 21:44:58.749405Z] Running create-virtualenv step. 13:44:58 INFO - Running pre-action listener: _resource_record_pre_action 13:44:58 INFO - Running main action method: create_virtualenv 13:44:58 INFO - Creating virtualenv /builds/slave/test/build/venv 13:44:58 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 13:44:58 INFO - Copy/paste: /tools/buildbot/bin/python /tools/misc-python/virtualenv.py --no-site-packages --distribute /builds/slave/test/build/venv 13:44:58 INFO - The --no-site-packages flag is deprecated; it is now the default behavior. 13:44:58 INFO - Using real prefix '/usr' 13:44:58 INFO - New python executable in /builds/slave/test/build/venv/bin/python 13:44:59 INFO - Installing distribute.............................................................................................................................................................................................done. 13:45:01 INFO - Installing pip.................done. 13:45:01 INFO - Return code: 0 13:45:01 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', '--version'] 13:45:01 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip --version 13:45:01 INFO - Reading from file tmpfile_stdout 13:45:01 INFO - Output received: 13:45:01 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) 13:45:01 INFO - Installing pip>=1.5 into virtualenv /builds/slave/test/build/venv 13:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:01 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:01 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 13:45:01 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 13:45:01 INFO - Using env: {'DISPLAY': ':0', 13:45:01 INFO - 'HOME': '/home/cltbld', 13:45:01 INFO - 'LANG': 'en_US.UTF-8', 13:45:01 INFO - 'LANGUAGE': 'en_US:en', 13:45:01 INFO - 'LOGNAME': 'cltbld', 13:45:01 INFO - 'MAIL': '/var/mail/cltbld', 13:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:01 INFO - 'MOZ_NO_REMOTE': '1', 13:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:01 INFO - 'NO_EM_RESTART': '1', 13:45:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:01 INFO - 'PWD': '/builds/slave/test', 13:45:01 INFO - 'SHELL': '/bin/bash', 13:45:01 INFO - 'SHLVL': '1', 13:45:01 INFO - 'TERM': 'linux', 13:45:01 INFO - 'TMOUT': '86400', 13:45:01 INFO - 'USER': 'cltbld', 13:45:01 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:01 INFO - '_': '/tools/buildbot/bin/python'} 13:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:01 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 13:45:01 INFO - Cleaning up... 13:45:01 INFO - Return code: 0 13:45:01 INFO - Installing psutil>=3.1.1 into virtualenv /builds/slave/test/build/venv 13:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:01 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:01 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:01 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:01 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:01 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:01 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:01 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 13:45:01 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 13:45:01 INFO - Using env: {'DISPLAY': ':0', 13:45:01 INFO - 'HOME': '/home/cltbld', 13:45:01 INFO - 'LANG': 'en_US.UTF-8', 13:45:01 INFO - 'LANGUAGE': 'en_US:en', 13:45:01 INFO - 'LOGNAME': 'cltbld', 13:45:01 INFO - 'MAIL': '/var/mail/cltbld', 13:45:01 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:01 INFO - 'MOZ_NO_REMOTE': '1', 13:45:01 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:01 INFO - 'NO_EM_RESTART': '1', 13:45:01 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:01 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:01 INFO - 'PWD': '/builds/slave/test', 13:45:01 INFO - 'SHELL': '/bin/bash', 13:45:01 INFO - 'SHLVL': '1', 13:45:01 INFO - 'TERM': 'linux', 13:45:01 INFO - 'TMOUT': '86400', 13:45:01 INFO - 'USER': 'cltbld', 13:45:01 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:01 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:01 INFO - '_': '/tools/buildbot/bin/python'} 13:45:01 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:01 INFO - Downloading/unpacking psutil>=3.1.1 13:45:01 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 13:45:01 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 13:45:01 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 13:45:01 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 13:45:01 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 13:45:01 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 13:45:03 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/psutil/setup.py) egg_info for package psutil 13:45:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:45:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:45:04 INFO - Installing collected packages: psutil 13:45:04 INFO - Running setup.py install for psutil 13:45:04 INFO - building 'psutil._psutil_linux' extension 13:45:04 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 13:45:04 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 13:45:04 INFO - building 'psutil._psutil_posix' extension 13:45:04 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 13:45:04 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 13:45:04 INFO - warning: no previously-included files matching '*' found under directory 'docs/_build' 13:45:04 INFO - warning: manifest_maker: MANIFEST.in, line 18: 'recursive-include' expects ... 13:45:04 INFO - Successfully installed psutil 13:45:04 INFO - Cleaning up... 13:45:04 INFO - Return code: 0 13:45:04 INFO - Installing mozsystemmonitor==0.3 into virtualenv /builds/slave/test/build/venv 13:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:04 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:04 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:04 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:04 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:04 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:04 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:04 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 13:45:04 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 13:45:04 INFO - Using env: {'DISPLAY': ':0', 13:45:04 INFO - 'HOME': '/home/cltbld', 13:45:04 INFO - 'LANG': 'en_US.UTF-8', 13:45:04 INFO - 'LANGUAGE': 'en_US:en', 13:45:04 INFO - 'LOGNAME': 'cltbld', 13:45:04 INFO - 'MAIL': '/var/mail/cltbld', 13:45:04 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:04 INFO - 'MOZ_NO_REMOTE': '1', 13:45:04 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:04 INFO - 'NO_EM_RESTART': '1', 13:45:04 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:04 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:04 INFO - 'PWD': '/builds/slave/test', 13:45:04 INFO - 'SHELL': '/bin/bash', 13:45:04 INFO - 'SHLVL': '1', 13:45:04 INFO - 'TERM': 'linux', 13:45:04 INFO - 'TMOUT': '86400', 13:45:04 INFO - 'USER': 'cltbld', 13:45:04 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:04 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:04 INFO - '_': '/tools/buildbot/bin/python'} 13:45:04 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:04 INFO - Downloading/unpacking mozsystemmonitor==0.3 13:45: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 13:45: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 13:45: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 13:45: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 13:45: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 13:45: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 13:45:06 INFO - Downloading mozsystemmonitor-0.3.tar.gz 13:45:06 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/mozsystemmonitor/setup.py) egg_info for package mozsystemmonitor 13:45:06 INFO - Requirement already satisfied (use --upgrade to upgrade): psutil>=3.1.1 in ./venv/lib/python2.7/site-packages (from mozsystemmonitor==0.3) 13:45:06 INFO - Installing collected packages: mozsystemmonitor 13:45:06 INFO - Running setup.py install for mozsystemmonitor 13:45:07 INFO - Successfully installed mozsystemmonitor 13:45:07 INFO - Cleaning up... 13:45:07 INFO - Return code: 0 13:45:07 INFO - Installing jsonschema==2.5.1 into virtualenv /builds/slave/test/build/venv 13:45:07 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')]} 13:45:07 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:07 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:07 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')]} 13:45:07 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:07 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:07 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:07 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 13:45:07 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 13:45:07 INFO - Using env: {'DISPLAY': ':0', 13:45:07 INFO - 'HOME': '/home/cltbld', 13:45:07 INFO - 'LANG': 'en_US.UTF-8', 13:45:07 INFO - 'LANGUAGE': 'en_US:en', 13:45:07 INFO - 'LOGNAME': 'cltbld', 13:45:07 INFO - 'MAIL': '/var/mail/cltbld', 13:45:07 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:07 INFO - 'MOZ_NO_REMOTE': '1', 13:45:07 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:07 INFO - 'NO_EM_RESTART': '1', 13:45:07 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:07 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:07 INFO - 'PWD': '/builds/slave/test', 13:45:07 INFO - 'SHELL': '/bin/bash', 13:45:07 INFO - 'SHLVL': '1', 13:45:07 INFO - 'TERM': 'linux', 13:45:07 INFO - 'TMOUT': '86400', 13:45:07 INFO - 'USER': 'cltbld', 13:45:07 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:07 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:07 INFO - '_': '/tools/buildbot/bin/python'} 13:45:07 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:07 INFO - Downloading/unpacking jsonschema==2.5.1 13:45:07 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 13:45:07 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 13:45:07 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 13:45:07 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 13:45:07 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 13:45:07 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 13:45:09 INFO - Downloading jsonschema-2.5.1-py2.py3-none-any.whl 13:45:09 INFO - Downloading/unpacking functools32 (from jsonschema==2.5.1) 13:45:09 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 13:45:09 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 13:45:09 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 13:45:09 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 13:45:09 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 13:45:09 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 13:45:09 INFO - Downloading functools32-3.2.3-2.tar.gz 13:45:09 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/functools32/setup.py) egg_info for package functools32 13:45:09 INFO - warning: no files found matching '*.txt' 13:45:09 INFO - no previously-included directories found matching 'build' 13:45:09 INFO - no previously-included directories found matching 'dist' 13:45:09 INFO - no previously-included directories found matching '.git*' 13:45:09 INFO - Installing collected packages: jsonschema, functools32 13:45:09 INFO - Running setup.py install for functools32 13:45:09 INFO - warning: no files found matching '*.txt' 13:45:09 INFO - no previously-included directories found matching 'build' 13:45:09 INFO - no previously-included directories found matching 'dist' 13:45:09 INFO - no previously-included directories found matching '.git*' 13:45:09 INFO - Successfully installed jsonschema functools32 13:45:09 INFO - Cleaning up... 13:45:09 INFO - Return code: 0 13:45:09 INFO - Installing functools32==3.2.3-2 into virtualenv /builds/slave/test/build/venv 13:45:09 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')]} 13:45:09 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:09 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:09 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')]} 13:45:09 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:09 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:09 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:09 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 13:45:09 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 13:45:09 INFO - Using env: {'DISPLAY': ':0', 13:45:09 INFO - 'HOME': '/home/cltbld', 13:45:09 INFO - 'LANG': 'en_US.UTF-8', 13:45:09 INFO - 'LANGUAGE': 'en_US:en', 13:45:09 INFO - 'LOGNAME': 'cltbld', 13:45:09 INFO - 'MAIL': '/var/mail/cltbld', 13:45:09 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:09 INFO - 'MOZ_NO_REMOTE': '1', 13:45:09 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:09 INFO - 'NO_EM_RESTART': '1', 13:45:09 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:09 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:09 INFO - 'PWD': '/builds/slave/test', 13:45:09 INFO - 'SHELL': '/bin/bash', 13:45:09 INFO - 'SHLVL': '1', 13:45:09 INFO - 'TERM': 'linux', 13:45:09 INFO - 'TMOUT': '86400', 13:45:09 INFO - 'USER': 'cltbld', 13:45:09 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:09 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:09 INFO - '_': '/tools/buildbot/bin/python'} 13:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:10 INFO - Requirement already satisfied (use --upgrade to upgrade): functools32==3.2.3-2 in ./venv/lib/python2.7/site-packages 13:45:10 INFO - Cleaning up... 13:45:10 INFO - Return code: 0 13:45:10 INFO - Installing blobuploader==1.2.4 into virtualenv /builds/slave/test/build/venv 13:45:10 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')]} 13:45:10 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:10 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:10 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')]} 13:45:10 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:10 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:10 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:10 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 13:45:10 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 13:45:10 INFO - Using env: {'DISPLAY': ':0', 13:45:10 INFO - 'HOME': '/home/cltbld', 13:45:10 INFO - 'LANG': 'en_US.UTF-8', 13:45:10 INFO - 'LANGUAGE': 'en_US:en', 13:45:10 INFO - 'LOGNAME': 'cltbld', 13:45:10 INFO - 'MAIL': '/var/mail/cltbld', 13:45:10 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:10 INFO - 'MOZ_NO_REMOTE': '1', 13:45:10 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:10 INFO - 'NO_EM_RESTART': '1', 13:45:10 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:10 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:10 INFO - 'PWD': '/builds/slave/test', 13:45:10 INFO - 'SHELL': '/bin/bash', 13:45:10 INFO - 'SHLVL': '1', 13:45:10 INFO - 'TERM': 'linux', 13:45:10 INFO - 'TMOUT': '86400', 13:45:10 INFO - 'USER': 'cltbld', 13:45:10 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:10 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:10 INFO - '_': '/tools/buildbot/bin/python'} 13:45:10 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:10 INFO - Downloading/unpacking blobuploader==1.2.4 13:45:10 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 13:45:10 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 13:45:10 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 13:45:10 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 13:45:10 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 13:45:10 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 13:45:12 INFO - Downloading blobuploader-1.2.4.tar.gz 13:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blobuploader/setup.py) egg_info for package blobuploader 13:45:12 INFO - Downloading/unpacking requests==1.2.3. (from blobuploader==1.2.4) 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/requests/setup.py) egg_info for package requests 13:45:12 INFO - Downloading/unpacking docopt==0.6.1 (from blobuploader==1.2.4) 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 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 13:45:12 INFO - Downloading docopt-0.6.1.tar.gz 13:45:13 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/docopt/setup.py) egg_info for package docopt 13:45:13 INFO - Installing collected packages: blobuploader, requests, docopt 13:45:13 INFO - Running setup.py install for blobuploader 13:45:13 INFO - changing mode of build/scripts-2.7/blobberc.py from 664 to 775 13:45:13 INFO - changing mode of /builds/slave/test/build/venv/bin/blobberc.py to 775 13:45:13 INFO - Running setup.py install for requests 13:45:13 INFO - Running setup.py install for docopt 13:45:13 INFO - Successfully installed blobuploader requests docopt 13:45:13 INFO - Cleaning up... 13:45:13 INFO - Return code: 0 13:45:13 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:45:13 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')]} 13:45:13 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:13 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:13 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')]} 13:45:13 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:13 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:13 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:13 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 13:45:13 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 13:45:13 INFO - Using env: {'DISPLAY': ':0', 13:45:13 INFO - 'HOME': '/home/cltbld', 13:45:13 INFO - 'LANG': 'en_US.UTF-8', 13:45:13 INFO - 'LANGUAGE': 'en_US:en', 13:45:13 INFO - 'LOGNAME': 'cltbld', 13:45:13 INFO - 'MAIL': '/var/mail/cltbld', 13:45:13 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:13 INFO - 'MOZ_NO_REMOTE': '1', 13:45:13 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:13 INFO - 'NO_EM_RESTART': '1', 13:45:13 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:13 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:13 INFO - 'PWD': '/builds/slave/test', 13:45:13 INFO - 'SHELL': '/bin/bash', 13:45:13 INFO - 'SHLVL': '1', 13:45:13 INFO - 'TERM': 'linux', 13:45:13 INFO - 'TMOUT': '86400', 13:45:13 INFO - 'USER': 'cltbld', 13:45:13 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:13 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:13 INFO - '_': '/tools/buildbot/bin/python'} 13:45:13 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:45:13 INFO - Running setup.py (path:/tmp/pip-WBPlX7-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:45:13 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:45:13 INFO - Running setup.py (path:/tmp/pip-SYnD3r-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:45:14 INFO - Running setup.py (path:/tmp/pip-4h0Ohg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:45:14 INFO - Running setup.py (path:/tmp/pip-7N6tPc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:45:14 INFO - Running setup.py (path:/tmp/pip-q9KYHN-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:45:14 INFO - Running setup.py (path:/tmp/pip-TrqYLJ-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:45:14 INFO - Running setup.py (path:/tmp/pip-5k6EsO-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:45:14 INFO - Running setup.py (path:/tmp/pip-3PY8XL-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:45:14 INFO - Running setup.py (path:/tmp/pip-Y04jmb-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:45:14 INFO - Running setup.py (path:/tmp/pip-yvSGEY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:45:14 INFO - Running setup.py (path:/tmp/pip-aq97bj-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:45:14 INFO - Running setup.py (path:/tmp/pip-GohLXx-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:45:14 INFO - Running setup.py (path:/tmp/pip-qNYFYY-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:45:14 INFO - Running setup.py (path:/tmp/pip-AficJa-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:45:14 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:45:14 INFO - Running setup.py (path:/tmp/pip-l8xEHd-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:45:15 INFO - Running setup.py (path:/tmp/pip-w0ZLbi-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:45:15 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:45:15 INFO - Running setup.py (path:/tmp/pip-M1rrFv-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:45:15 INFO - Installing collected packages: manifestparser, mozcrash, mozdebug, mozdevice, mozfile, mozhttpd, mozinfo, mozInstall, mozleak, mozlog, moznetwork, mozprocess, mozprofile, mozrunner, mozscreenshot, moztest, mozversion 13:45:15 INFO - Running setup.py install for manifestparser 13:45:15 INFO - Installing manifestparser script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Running setup.py install for mozcrash 13:45:15 INFO - Running setup.py install for mozdebug 13:45:15 INFO - Running setup.py install for mozdevice 13:45:15 INFO - Installing sutini script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Installing dm script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Running setup.py install for mozfile 13:45:15 INFO - Running setup.py install for mozhttpd 13:45:15 INFO - Installing mozhttpd script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Running setup.py install for mozinfo 13:45:15 INFO - Installing mozinfo script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Running setup.py install for mozInstall 13:45:15 INFO - Installing moz_remove_from_system script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Installing mozuninstall script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Installing mozinstall script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Installing moz_add_to_system script to /builds/slave/test/build/venv/bin 13:45:15 INFO - Running setup.py install for mozleak 13:45:16 INFO - Running setup.py install for mozlog 13:45:16 INFO - Installing structlog script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Running setup.py install for moznetwork 13:45:16 INFO - Installing moznetwork script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Running setup.py install for mozprocess 13:45:16 INFO - Running setup.py install for mozprofile 13:45:16 INFO - Installing mozprofile script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Installing diff-profiles script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Installing view-profile script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Running setup.py install for mozrunner 13:45:16 INFO - Installing mozrunner script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Running setup.py install for mozscreenshot 13:45:16 INFO - Running setup.py install for moztest 13:45:16 INFO - Running setup.py install for mozversion 13:45:16 INFO - Installing mozversion script to /builds/slave/test/build/venv/bin 13:45:16 INFO - Successfully installed manifestparser mozcrash mozdebug mozdevice mozfile mozhttpd mozinfo mozInstall mozleak mozlog moznetwork mozprocess mozprofile mozrunner mozscreenshot moztest mozversion 13:45:16 INFO - Cleaning up... 13:45:16 INFO - Return code: 0 13:45:16 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:45:16 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')]} 13:45:16 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:16 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:16 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')]} 13:45:16 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:16 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:17 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:17 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 13:45:17 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 13:45:17 INFO - Using env: {'DISPLAY': ':0', 13:45:17 INFO - 'HOME': '/home/cltbld', 13:45:17 INFO - 'LANG': 'en_US.UTF-8', 13:45:17 INFO - 'LANGUAGE': 'en_US:en', 13:45:17 INFO - 'LOGNAME': 'cltbld', 13:45:17 INFO - 'MAIL': '/var/mail/cltbld', 13:45:17 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:17 INFO - 'MOZ_NO_REMOTE': '1', 13:45:17 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:17 INFO - 'NO_EM_RESTART': '1', 13:45:17 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:17 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:17 INFO - 'PWD': '/builds/slave/test', 13:45:17 INFO - 'SHELL': '/bin/bash', 13:45:17 INFO - 'SHLVL': '1', 13:45:17 INFO - 'TERM': 'linux', 13:45:17 INFO - 'TMOUT': '86400', 13:45:17 INFO - 'USER': 'cltbld', 13:45:17 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:17 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:17 INFO - '_': '/tools/buildbot/bin/python'} 13:45:17 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/manifestparser 13:45:17 INFO - Running setup.py (path:/tmp/pip-AJvKKM-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/manifestparser 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozcrash 13:45:17 INFO - Running setup.py (path:/tmp/pip-ZjLYsl-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozcrash 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdebug 13:45:17 INFO - Running setup.py (path:/tmp/pip-6rXCpy-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdebug 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozdevice 13:45:17 INFO - Running setup.py (path:/tmp/pip-uyOzZ4-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozdevice 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozfile 13:45:17 INFO - Running setup.py (path:/tmp/pip-FDSEhr-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozfile 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozhttpd 13:45:17 INFO - Running setup.py (path:/tmp/pip-imUFgA-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozhttpd 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinfo 13:45:17 INFO - Running setup.py (path:/tmp/pip-rU7woc-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinfo 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozinstall 13:45:17 INFO - Running setup.py (path:/tmp/pip-NbG458-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozinstall 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozleak 13:45:17 INFO - Running setup.py (path:/tmp/pip-rnA6f8-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozleak 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozlog 13:45:17 INFO - Running setup.py (path:/tmp/pip-6vzWha-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozlog 13:45:17 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)) 13:45:17 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moznetwork 13:45:17 INFO - Running setup.py (path:/tmp/pip-VCq139-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moznetwork 13:45:18 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)) 13:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprocess 13:45:18 INFO - Running setup.py (path:/tmp/pip-33U8Cg-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprocess 13:45:18 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)) 13:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozprofile 13:45:18 INFO - Running setup.py (path:/tmp/pip-gcnfjR-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozprofile 13:45:18 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)) 13:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozrunner 13:45:18 INFO - Running setup.py (path:/tmp/pip-07n7Em-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozrunner 13:45:18 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)) 13:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozscreenshot 13:45:18 INFO - Running setup.py (path:/tmp/pip-Nu7uCU-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozscreenshot 13:45:18 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)) 13:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/moztest 13:45:18 INFO - Running setup.py (path:/tmp/pip-S4hwrD-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/moztest 13:45:18 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)) 13:45:18 INFO - Unpacking /builds/slave/test/build/tests/mozbase/mozversion 13:45:18 INFO - Running setup.py (path:/tmp/pip-BIS6da-build/setup.py) egg_info for package from file:///builds/slave/test/build/tests/mozbase/mozversion 13:45:18 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)) 13:45:18 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)) 13:45:18 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)) 13:45:18 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)) 13:45:18 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)) 13:45:18 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)) 13:45:18 INFO - Downloading/unpacking blessings>=1.3 (from mozlog==3.4->-r /builds/slave/test/build/tests/config/mozbase_requirements.txt (line 10)) 13:45:18 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 13:45:18 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 13:45:18 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 13:45:18 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 13:45:18 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 13:45:18 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 13:45:20 INFO - Downloading blessings-1.6.tar.gz 13:45:20 INFO - Running setup.py (path:/builds/slave/test/build/venv/build/blessings/setup.py) egg_info for package blessings 13:45:20 INFO - Installing collected packages: blessings 13:45:20 INFO - Running setup.py install for blessings 13:45:20 INFO - Successfully installed blessings 13:45:20 INFO - Cleaning up... 13:45:20 INFO - Return code: 0 13:45:20 INFO - Done creating virtualenv /builds/slave/test/build/venv. 13:45:20 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:45:20 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:45:20 INFO - Reading from file tmpfile_stdout 13:45:20 INFO - Current package versions: 13:45:20 INFO - argparse == 1.2.1 13:45:20 INFO - blessings == 1.6 13:45:20 INFO - blobuploader == 1.2.4 13:45:20 INFO - docopt == 0.6.1 13:45:20 INFO - functools32 == 3.2.3-2 13:45:20 INFO - jsonschema == 2.5.1 13:45:20 INFO - manifestparser == 1.1 13:45:20 INFO - mozInstall == 1.12 13:45:20 INFO - mozcrash == 1.0 13:45:20 INFO - mozdebug == 0.1 13:45:20 INFO - mozdevice == 0.49 13:45:20 INFO - mozfile == 1.2 13:45:20 INFO - mozhttpd == 0.7 13:45:20 INFO - mozinfo == 0.9 13:45:20 INFO - mozleak == 0.1 13:45:20 INFO - mozlog == 3.4 13:45:20 INFO - moznetwork == 0.27 13:45:20 INFO - mozprocess == 0.25 13:45:20 INFO - mozprofile == 0.28 13:45:20 INFO - mozrunner == 6.13 13:45:20 INFO - mozscreenshot == 0.1 13:45:20 INFO - mozsystemmonitor == 0.3 13:45:20 INFO - moztest == 0.8 13:45:20 INFO - mozversion == 1.4 13:45:20 INFO - psutil == 3.1.1 13:45:20 INFO - requests == 1.2.3 13:45:20 INFO - wsgiref == 0.1.2 13:45:20 INFO - Installing None into virtualenv /builds/slave/test/build/venv 13:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:20 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:20 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:20 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:20 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:20 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:20 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:20 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 13:45:20 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 13:45:20 INFO - Using env: {'DISPLAY': ':0', 13:45:20 INFO - 'HOME': '/home/cltbld', 13:45:20 INFO - 'LANG': 'en_US.UTF-8', 13:45:20 INFO - 'LANGUAGE': 'en_US:en', 13:45:20 INFO - 'LOGNAME': 'cltbld', 13:45:20 INFO - 'MAIL': '/var/mail/cltbld', 13:45:20 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:20 INFO - 'MOZ_NO_REMOTE': '1', 13:45:20 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:20 INFO - 'NO_EM_RESTART': '1', 13:45:20 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:20 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:20 INFO - 'PWD': '/builds/slave/test', 13:45:20 INFO - 'SHELL': '/bin/bash', 13:45:20 INFO - 'SHLVL': '1', 13:45:20 INFO - 'TERM': 'linux', 13:45:20 INFO - 'TMOUT': '86400', 13:45:20 INFO - 'USER': 'cltbld', 13:45:20 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:20 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:20 INFO - '_': '/tools/buildbot/bin/python'} 13:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 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)) 13:45:21 INFO - Cleaning up... 13:45:21 INFO - Return code: 0 13:45:21 INFO - Installing jsonschema into virtualenv /builds/slave/test/build/venv 13:45:21 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')]} 13:45:21 INFO - http://pypi.pvt.build.mozilla.org/pub matches http://pypi.pvt.build.mozilla.org 13:45:21 INFO - URL Candidate: http://pypi.pvt.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:21 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')]} 13:45:21 INFO - http://pypi.pub.build.mozilla.org/pub matches http://pypi.pub.build.mozilla.org 13:45:21 INFO - URL Candidate: http://pypi.pub.build.mozilla.org.proxxy1.srv.releng.scl3.mozilla.com/pub 13:45:21 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 0x15b61f0>, 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x16b0a80>, 'level': 'warning'}, {'regex': <_sre.SRE_Pattern object at 0x1856ed0>, 'level': 'debug'}, {'substr': 'command not found', 'level': 'error'}, {'regex': <_sre.SRE_Pattern object at 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, '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': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', '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 13:45:21 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 13:45:21 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 13:45:21 INFO - Using env: {'DISPLAY': ':0', 13:45:21 INFO - 'HOME': '/home/cltbld', 13:45:21 INFO - 'LANG': 'en_US.UTF-8', 13:45:21 INFO - 'LANGUAGE': 'en_US:en', 13:45:21 INFO - 'LOGNAME': 'cltbld', 13:45:21 INFO - 'MAIL': '/var/mail/cltbld', 13:45:21 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:21 INFO - 'MOZ_NO_REMOTE': '1', 13:45:21 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:21 INFO - 'NO_EM_RESTART': '1', 13:45:21 INFO - 'PATH': '/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:21 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:21 INFO - 'PWD': '/builds/slave/test', 13:45:21 INFO - 'SHELL': '/bin/bash', 13:45:21 INFO - 'SHLVL': '1', 13:45:21 INFO - 'TERM': 'linux', 13:45:21 INFO - 'TMOUT': '86400', 13:45:21 INFO - 'USER': 'cltbld', 13:45:21 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:21 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:21 INFO - '_': '/tools/buildbot/bin/python'} 13:45:21 INFO - Ignoring indexes: https://pypi.python.org/simple/ 13:45:21 INFO - Requirement already satisfied (use --upgrade to upgrade): jsonschema in ./venv/lib/python2.7/site-packages 13:45:21 INFO - Cleaning up... 13:45:21 INFO - Return code: 0 13:45:21 INFO - Running post-action listener: _resource_record_post_action 13:45:21 INFO - Running post-action listener: _start_resource_monitoring 13:45:21 INFO - Starting resource monitoring. 13:45:21 INFO - [mozharness: 2017-02-17 21:45:21.420418Z] Finished create-virtualenv step (success) 13:45:21 INFO - [mozharness: 2017-02-17 21:45:21.420886Z] Running install step. 13:45:21 INFO - Running pre-action listener: _resource_record_pre_action 13:45:21 INFO - Running main action method: install 13:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/pip', 'freeze'] 13:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/pip freeze 13:45:21 INFO - Reading from file tmpfile_stdout 13:45:21 INFO - Detecting whether we're running mozinstall >=1.0... 13:45:21 INFO - Getting output from command: ['/builds/slave/test/build/venv/bin/mozinstall', '-h'] 13:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall -h 13:45:21 INFO - Reading from file tmpfile_stdout 13:45:21 INFO - Output received: 13:45:21 INFO - Usage: mozinstall [options] installer 13:45:21 INFO - Options: 13:45:21 INFO - -h, --help show this help message and exit 13:45:21 INFO - -d DEST, --destination=DEST 13:45:21 INFO - Directory to install application into. [default: 13:45:21 INFO - "/builds/slave/test"] 13:45:21 INFO - --app=APP Application being installed. [default: firefox] 13:45:21 INFO - mkdir: /builds/slave/test/build/application 13:45:21 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'] 13:45:21 INFO - Copy/paste: /builds/slave/test/build/venv/bin/mozinstall /builds/slave/test/build/target.tar.bz2 --destination /builds/slave/test/build/application 13:45:38 INFO - Reading from file tmpfile_stdout 13:45:38 INFO - Output received: 13:45:38 INFO - /builds/slave/test/build/application/firefox/firefox 13:45:38 INFO - Running post-action listener: _resource_record_post_action 13:45:38 INFO - [mozharness: 2017-02-17 21:45:38.336578Z] Finished install step (success) 13:45:38 INFO - [mozharness: 2017-02-17 21:45:38.336853Z] Running run-tests step. 13:45:38 INFO - Running pre-action listener: _resource_record_pre_action 13:45:38 INFO - Running main action method: run_tests 13:45:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '--version'] 13:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python --version 13:45:38 INFO - Python 2.7.3 13:45:38 INFO - Return code: 0 13:45:38 INFO - grabbing minidump binary from tooltool 13:45:38 INFO - proxxy config: {'regions': ['.use1.', '.usw2.', '.scl3'], 'instances': ['proxxy1.srv.releng.use1.mozilla.com', 'proxxy1.srv.releng.usw2.mozilla.com', 'proxxy1.srv.releng.scl3.mozilla.com'], 'urls': [('http://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp.mozilla.org', 'ftp.mozilla.org'), ('https://ftp-ssl.mozilla.org', 'ftp.mozilla.org'), ('http://pypi.pvt.build.mozilla.org', 'pypi.pvt.build.mozilla.org'), ('http://pypi.pub.build.mozilla.org', 'pypi.pub.build.mozilla.org')]} 13:45:38 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 0x18e0540>, '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 0x1854f70>, 'level': 'critical'}, {'regex': <_sre.SRE_Pattern object at 0x1855400>, 'level': 'critical'}, {'substr': 'ERROR - ', 'level': 'error'}], 'cwd': '/builds/slave/test/build', 'privileged': False}, attempt #1 13:45:38 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 13:45:38 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 13:45:38 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 13:45:38 INFO - INFO - File linux64-minidump_stackwalk retrieved from local cache /builds/tooltool_cache 13:45:38 INFO - Return code: 0 13:45:38 INFO - Chmoding /builds/slave/test/build/linux64-minidump_stackwalk to 0755 13:45:38 INFO - mkdir: /builds/slave/test/build/blobber_upload_dir 13:45:38 INFO - ENV: MOZ_UPLOAD_DIR is now /builds/slave/test/build/blobber_upload_dir 13:45:38 INFO - ENV: RUST_BACKTRACE is now 1 13:45:38 INFO - ENV: MINIDUMP_STACKWALK is now /builds/slave/test/build/linux64-minidump_stackwalk 13:45:38 INFO - ENV: MINIDUMP_SAVE_PATH is now /builds/slave/test/build/blobber_upload_dir 13:45:38 INFO - Running command: ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] in /builds/slave/test/build 13:45:38 INFO - Copy/paste: /builds/slave/test/build/venv/bin/python /builds/slave/test/build/tests/talos/talos/run_tests.py --branchName Firefox --suite dromaeojs --executablePath /builds/slave/test/build/application/firefox/firefox --symbolsPath https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.crashreporter-symbols.zip --title talos-linux64-ix-084 --webServer localhost --log-tbpl-level=debug --log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log --log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log 13:45:38 INFO - Using env: {'DISPLAY': ':0', 13:45:38 INFO - 'HOME': '/home/cltbld', 13:45:38 INFO - 'LANG': 'en_US.UTF-8', 13:45:38 INFO - 'LANGUAGE': 'en_US:en', 13:45:38 INFO - 'LOGNAME': 'cltbld', 13:45:38 INFO - 'MAIL': '/var/mail/cltbld', 13:45:38 INFO - 'MINIDUMP_SAVE_PATH': '/builds/slave/test/build/blobber_upload_dir', 13:45:38 INFO - 'MINIDUMP_STACKWALK': '/builds/slave/test/build/linux64-minidump_stackwalk', 13:45:38 INFO - 'MOZ_CRASHREPORTER_NO_REPORT': '1', 13:45:38 INFO - 'MOZ_NO_REMOTE': '1', 13:45:38 INFO - 'MOZ_UPLOAD_DIR': '/builds/slave/test/build/blobber_upload_dir', 13:45:38 INFO - 'NODE_PATH': '/usr/lib/nodejs:/usr/lib/node_modules:/usr/share/javascript', 13:45:38 INFO - 'NO_EM_RESTART': '1', 13:45:38 INFO - 'PATH': '/builds/slave/test/build/venv/bin:/usr/local/bin:/usr/local/bin:/usr/bin:/bin:/usr/local/games:/usr/games', 13:45:38 INFO - 'PROPERTIES_FILE': '/builds/slave/test/buildprops.json', 13:45:38 INFO - 'PWD': '/builds/slave/test', 13:45:38 INFO - 'PYTHONPATH': '/builds/slave/test/build/tests/talos', 13:45:38 INFO - 'RUST_BACKTRACE': '1', 13:45:38 INFO - 'SHELL': '/bin/bash', 13:45:38 INFO - 'SHLVL': '1', 13:45:38 INFO - 'TERM': 'linux', 13:45:38 INFO - 'TMOUT': '86400', 13:45:38 INFO - 'USER': 'cltbld', 13:45:38 INFO - 'XDG_SESSION_COOKIE': 'ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490', 13:45:38 INFO - 'XPCOM_DEBUG_BREAK': 'warn', 13:45:38 INFO - '_': '/tools/buildbot/bin/python'} 13:45:38 INFO - Calling ['/builds/slave/test/build/venv/bin/python', '/builds/slave/test/build/tests/talos/talos/run_tests.py', '--branchName', 'Firefox', '--suite', 'dromaeojs', '--executablePath', '/builds/slave/test/build/application/firefox/firefox', '--symbolsPath', 'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.crashreporter-symbols.zip', '--title', 'talos-linux64-ix-084', '--webServer', 'localhost', '--log-tbpl-level=debug', '--log-errorsummary=/builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log', '--log-raw=/builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log'] with output_timeout 3600 13:45:38 INFO - mozversion application_buildid: 20170217204551 13:45:38 INFO - mozversion application_changeset: f302def88fe5040923ffc2295b7c804b693e7b79 13:45:38 INFO - mozversion application_display_name: Nightly 13:45:38 INFO - mozversion application_id: {ec8030f7-c20a-464f-9b0e-13a3a9e97384} 13:45:38 INFO - mozversion application_name: Firefox 13:45:38 INFO - mozversion application_remotingname: firefox 13:45:38 INFO - mozversion application_repository: https://hg.mozilla.org/mozilla-central 13:45:38 INFO - mozversion application_vendor: Mozilla 13:45:38 INFO - mozversion application_version: 54.0a1 13:45:38 INFO - mozversion platform_buildid: 20170217204551 13:45:38 INFO - mozversion platform_changeset: f302def88fe5040923ffc2295b7c804b693e7b79 13:45:38 INFO - mozversion platform_repository: https://hg.mozilla.org/mozilla-central 13:45:38 INFO - mozversion platform_version: 54.0a1 13:45:38 INFO - using testdate: 1487367938 13:45:38 INFO - actual date: 1487367938 13:45:38 INFO - starting webserver on 'localhost:45915' 13:45:38 INFO - SUITE-START | Running 2 tests 13:45:38 INFO - TEST-START | dromaeo_css 13:45:38 INFO - operating with platform_type : linux_ 13:45:38 INFO - Initialising browser for dromaeo_css test... 13:45:38 INFO - TEST-INFO | started process 1075 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH0SiiW/profile http://localhost:45915/getInfo.html) 13:45:41 INFO - PROCESS | 1075 | Iterating over profile /tmp/tmpH0SiiW/profile 13:45:42 INFO - PROCESS | 1075 | __metrics Screen width/height:1600/1200 13:45:42 INFO - PROCESS | 1075 | colorDepth:24 13:45:42 INFO - PROCESS | 1075 | Browser inner width/height: 1024/697 13:45:42 INFO - PROCESS | 1075 | __metrics 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/minidumps = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/permissions.sqlite = Fri Feb 17 2017 13:45:39 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | Stat failure 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/places.sqlite = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/webappsstore.sqlite = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/storage.sqlite = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/mimeTypes.rdf = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/key3.db = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/blocklist.xml = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/places.sqlite-shm = Fri Feb 17 2017 13:45:40 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/localstore.rdf = Fri Feb 17 2017 13:44:57 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/compatibility.ini = Fri Feb 17 2017 13:45:39 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/pluginreg.dat = Fri Feb 17 2017 13:45:41 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/cookies.sqlite = Fri Feb 17 2017 13:45:41 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/gmp = Fri Feb 17 2017 13:45:39 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/secmod.db = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/frequencyCap.json = Fri Feb 17 2017 13:45:41 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/safebrowsing = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | Stat failure 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/places.sqlite-wal = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/cache2 = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:42 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/directoryLinks.json = Fri Feb 17 2017 13:45:41 GMT-0800 (PST) 13:45:42 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/sessionCheckpoints.json = Fri Feb 17 2017 13:45:42 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/extensions.ini = Fri Feb 17 2017 13:45:39 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/crashes = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/.parentlock = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/extensions = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | Stat failure 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/content-prefs.sqlite = Fri Feb 17 2017 13:45:40 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/startupCache = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/extensions.json = Fri Feb 17 2017 13:45:41 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/bookmarkbackups = Fri Feb 17 2017 13:45:40 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/addons.json = Fri Feb 17 2017 13:45:41 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/thumbnails = Fri Feb 17 2017 13:45:40 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/cert8.db = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/user.js = Fri Feb 17 2017 13:45:38 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | No birth date. Using mtime. 13:45:43 INFO - PROCESS | 1075 | Using date: /tmp/tmpH0SiiW/profile/prefs.js = Fri Feb 17 2017 13:44:57 GMT-0800 (PST) 13:45:43 INFO - PROCESS | 1075 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 13:45:43 INFO - PROCESS | 1075 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 13:45:44 INFO - PROCESS | 1075 | console.error: PushService: 13:45:44 INFO - PROCESS | 1075 | stateChangeProcessEnqueue: Error transitioning state 13:45:44 INFO - PROCESS | 1075 | UnknownError 13:45:44 INFO - TEST-INFO | 1075: exit 0 13:45:44 INFO - Browser initialized. 13:45:44 INFO - Running cycle 1/1 for dromaeo_css test... 13:45:44 INFO - TEST-INFO | started process 1234 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpH0SiiW/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/dromaeo/css.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 13:45:56 INFO - PROCESS | 1234 | RSS: Main: 143097856 13:45:56 INFO - PROCESS | 1234 | 13:48:57 INFO - PROCESS | 1234 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-dojo.html (next: http://localhost:45915/tests/dromaeo/cssquery-ext.html) 13:48:59 INFO - PROCESS | 1234 | RSS: Main: 371617792 13:48:59 INFO - PROCESS | 1234 | 13:50:46 INFO - PROCESS | 1234 | ************************* 13:50:46 INFO - PROCESS | 1234 | A coding exception was thrown and uncaught in a Task. 13:50:46 INFO - PROCESS | 1234 | 13:50:46 ERROR - PROCESS | 1234 | Full message: TypeError: NetworkError when attempting to fetch resource. 13:50:46 INFO - PROCESS | 1234 | Full stack: 13:50:46 INFO - PROCESS | 1234 | ************************* 13:52:02 INFO - PROCESS | 1234 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-ext.html (next: http://localhost:45915/tests/dromaeo/cssquery-jquery.html) 13:52:03 INFO - PROCESS | 1234 | RSS: Main: 249319424 13:52:03 INFO - PROCESS | 1234 | 13:55:05 INFO - PROCESS | 1234 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-jquery.html (next: http://localhost:45915/tests/dromaeo/cssquery-mootools.html) 13:55:05 INFO - PROCESS | 1234 | RSS: Main: 250130432 13:55:05 INFO - PROCESS | 1234 | 13:58:07 INFO - PROCESS | 1234 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-mootools.html (next: http://localhost:45915/tests/dromaeo/cssquery-prototype.html) 13:58:08 INFO - PROCESS | 1234 | RSS: Main: 191930368 13:58:08 INFO - PROCESS | 1234 | 14:01:11 INFO - PROCESS | 1234 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-prototype.html (next: http://localhost:45915/tests/dromaeo/cssquery-yui.html) 14:01:11 INFO - PROCESS | 1234 | RSS: Main: 181096448 14:01:11 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | Cycle 1(1): loaded http://localhost:45915/tests/dromaeo/cssquery-yui.html (next: http://localhost:45915/tests/dromaeo/cssquery-dojo.html) 14:04:14 INFO - PROCESS | 1234 | RSS: Main: 273686528 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | __start_tp_report 14:04:14 INFO - PROCESS | 1234 | _x_x_mozilla_page_load 14:04:14 INFO - PROCESS | 1234 | _x_x_mozilla_page_load_details 14:04:14 INFO - PROCESS | 1234 | |i|pagename|runs| 14:04:14 INFO - PROCESS | 1234 | |0;dojo.html;3088.8764449422038;3091.799033062851;3124.906252812415;3131.8747250109996;3164.9683503164974;5155.175171972485;5191.325127733394;5201.427842937273;5203.271541984116;5257.211418287259;5148.124818780807;5155.123628983072;5158.7936482540745;5162.870928226796;5229.241759944805;4383.802728877192;4591.058832939256;4714.010057887847;4746.715197088169;4770.117528257266;4526.932096018562;4548.818047278105;4703.529647035286;4722.433308003045;4737.194676904935;4587.793549290273;4604.125216208927;4606.631469482433;4660.16117098922;4681.297805329228;4696.060787842411;4700.2479603263655;4708.811647534095;4737.549932756418;4764.976175119137;4651.3255577941345;4685.2269375553;4698.882527936795;4706.482286948433;4764;5009.474005229435;5028.748562571856;5046.646734728571;5078.060558796635;5214;5384.3538775346815;5420.7018613976215;5432.891342173134;5433.320834895638;5433.565314774809;5547.306586676666;5560.221568980346;5564.33228012637;5566.916496252519;5568.248286481355;5157.819476318311;5158.484151584849;5165.870853228662;5167.250748641426;5173.689578625257;5542.889142217133;5616.663000219918;5625.46558076982;5628.549716022709;5632.352279487911;5312.867178320573;5338.706371149624;5338.733063346817;5418.647787893774;5420.891582168335;5477.45225477457;5494.80768173112;5506.394296627368;5507.311586051744;5510.228568000483;5341.866453338697;5379;5387.515123638891;5387.865303367447;5438.646487978269;5438.483344082306;5444.91832622511;5481;5506.752196151183;5596.888062238732;4275.700700950966;4319.827206911751;4320.524742278347;4350.695451318377;4355.869323920288;3879.476270703475;3903.375459926398;3914.72596918213;3920.4315374270573;3922.0783115967724;5075;5116.948830511647;5117.02776472469;5176.456472070454;5212.765425555859;5495.230667706524;5505.807296744595;5509.449055094458;5527.806526771544;5575.972120139373;5286.60350473716;5294.7617357219015;5303.549198318186;5303.602229832778;5303.946960530346;4718.174319494075;4777.904441911142;4787.952120478751;4788.353572267699;4828.227483602606;5696.914546281809;5704.629199102045;5725.083986562046;5751.281089863767;5763.9135412968835;5437.728113594226;5462.481064298885;5465.344158700981;5527.53015993645;5539.362973258127;5664.631798933056;5689.203511508475;5692.743826527733;5697.173909783141;5698.31620205578;5425.13197888336;5485.753141108739;5535.750891209986;5538.501534861882;5538.944610553843;5512.834614961557;5556.138798486241;5556.16657501378;5563.276774019513;5601.523870470893;5448.972755136199;5449.536789372948;5449.7002664852225;5453.972730136324;5500.80747173847;5303.734813259399;5347.518723314764;5349.732513374393;5403.216533602685;5443.782248710166;5312.389075256395;5414.972925135349;5416.214648875812;5423.186522021729;5424.186372044225;5418.133098704188;5458.208559758733;5471.151971444432;5529.723513824373;5540.722963851872;5236.6072544560075;5338.919916201337;5339.145736682268;5349.598780091586;5377 14:04:14 INFO - PROCESS | 1234 | |1;ext.html;566.9007923613368;586.2935163128433;586.4106572894243;597.4921316880651;602.0848310567934;29705.25736856577;41150.58849411505;43496.782516087456;43538;47040.29439558401;45304.3204351935;45725.54274457254;45731.77134114325;45761.54238457615;45806.7709661452;9083.863742043857;9253.58358873849;9272.67545635903;9280.535973201348;9284.303677224201;7412.55524668519;7527.0967483902;10874.401907895062;11181.664550063511;11208.21542492026;10815.891841081568;10905.18211134164;10906.890931090707;10908.454577271144;10910.345379277229;10891.455427228646;10906.61826836065;10914.781704365947;10921.836172457419;10921.945390273077;10191;10268.845967310497;10319.484025798754;10330.535125919352;10332.535035923402;41696.58303416958;41955.58044419578;41965;42045.36931946023;42046.579534204575;3739.4577786221116;3744.906377340561;3773.47171396002;3793.7344385892998;3816.732828701992;26917.730822691916;28746.71253287461;29122.70877291221;29219.41561168786;29429.11712648624;3669.3028324618235;3729.7948612826285;3814.275287695329;3830.9042273943096;3928.9017774555577;298410;302250;302401;302599;304125;43864;51381.743091284305;51846.74076629668;51869.74065129726;51945.74027129916;43325.566744332784;53310.73344633252;54824.45175548273;54824.95388260972;60363.39636603578;15560.377584896478;15646.295916683777;15771.44799931997;15782.605434864221;15794.60513487149;12133.393330333449;12164.695882603006;12206.08454365926;12219.26684398939;12220.633380998584;16791.496255112364;16988.74516882248;17010.19331810262;17097.922373972557;17110.657786844193;6645.468362530986;6676.566023208476;6765.932340676531;6822.488313376417;6862.9313706863295;885.1148851148852;895.6193617712446;895.9372843900994;906.4515967839362;919.6367434863325;30078.941373401594;31255.843720781704;31291.374172516877;31587.839526537507;31801.52297715536;1045.2526443592715;1061.400308825511;1063.3247887591435;1068.5618896252654;1225.559967038726;15172.620684482756;15929.761053584205;16142.112183829768;16300.505238314852;16357.345706171858;498043;501244;502125;503177;503844;60136;60720.696396517735;60730;60768.39231607716;60898.695506522185;1307.9280639564724;1376.6695992961618;1388.125480946997;1390.3604342002793;1395.6720170759863;28341.71658283473;30289.545656815608;30394.544081838347;30781.272447154683;30856.382872342878;50706.74646626743;51201.74399127981;51343.48656513536;51347.74326128345;51371.74314128405;351042;352689;353031;353207;353972;23320.183793567143;23691.28926132219;25244.74755252424;26850.731492684823;27415.17754467369;45609.77195114136;45777;45799.313010304206;46515;46553.22382299755;34319.828400857834;34702.65297346994;35064.47403288902;38750.224995500495;39066;35298.823505882305;35351.469727954616;35608.82195589005;35694.4645830318;35854.64145358513;35469.645303547666;35765.82117089502;36392.81803590965;36557.08607284839;36844.26311473702;30207;32093;32161.195970100936;32190.873319433707;32599.18502037468;24242.393940151636;24461.877690611433;24639.8768006166;24876.751232487444;24951.251462456155 14:04:14 INFO - PROCESS | 1234 | |2;jquery.html;409.4104489535067;412.4885142423392;419.9895002624934;423.97668128252946;432.5199029077723;17485.7377139343;18281.63436731266;18992.430227093173;20171.29400470984;20171.899140504294;20031.298904538344;20242.89878550605;20628;20867.478313042182;20913.686294705592;5572.804951826687;5995.1906492623375;6027.397260273981;6041.395860413945;6041.9697901510435;5849;5939.67331796751;5941.257342832146;5945.672987985659;7231.240719724432;7514.661840217176;7717.575533345664;7718.343940765042;7732.922670773305;7745.728899488519;7318.560886346836;7602.961985190094;7642.770716878503;7662.73180438682;7724.150343462215;7207.45944054198;7406.185319614838;7448.441366897504;7462.626868656545;7465.328120469129;25833.225003249932;25871.353216169748;26440.206793796217;26457.867710661518;26782.86608566964;2835.4187391584674;2920.8831646734106;2921.956170657442;2965.4958657028355;2983.4331477019296;10420.531076101593;15374.846251537454;15529.907657578908;15702.136382498958;16024.157221919511;1912.2159914435153;1967.9311224107087;1972.9506762330914;1988.2146552111835;2008.6284037452972;102667;104173;104269;105337;106769;18546.81453185451;18595.628087438174;18813.623727525646;18921.7161742574;18980.810191897905;34365.828170858986;34607.82696086554;34617.653823461944;34692.826535867665;34832.30335393278;10088.949555252324;10247.282690211614;10289.794204115875;10362.326448780805;10597.9470102649;8381.664733410595;8413.747587572383;8478.788030299293;8610.311175105977;8649.870251946226;9492.667756628485;9628;9656.806863862683;9675;9732.90267097334;3725.9441108383394;3800.4869342638945;3929.548101968253;3959.366501359769;3960.9207815844097;820.8973878265217;854.9786255343666;863.456022705704;872.6291326186346;879.5778026547293;19476.70784938227;19539.80460195408;20220.69668954967;20240;20334.694979575317;848.3679658654219;851.67210623909;867.2324992381788;871.1419252036866;874.3486102853287;10436.42599657011;10946.288491248044;10981.17641176915;11073.667789966314;11091.22361434708;548247;548671;549326;551778;584428;72399.6380018107;76569.23430765621;100250.4987475058;104429;107683;1017.308230403318;1049.7533079726259;1065.1372388365294;1070.373831308693;1075.9462026898623;16377.836221638108;16569.585760356087;16732;17204;17823;42777.30115200194;43374;43407.34888976605;44700;44913.77543112264;58084.709576451845;59478.40521594902;59711.701441492514;60966.69516652566;61545.38454615397;7914.920850791418;7985.321247694011;8699.478031318142;8806.163414475615;9190.58642361082;12746.10777245584;12871.806922895978;12944.8058279124;13262.270575118075;13459.730805384033;5917.822465326047;6737.59574425536;10920.945395272973;11093.889061109609;11547.769044619228;11190.160737944523;11214.215004949576;11341.432928353384;11369.602063927723;11407.885921140682;11404.087672986307;11808;11816.70458238551;11821.82267266009;11883.58407455735;7846.450748447553;7869.0950540688555;7870.212978702084;7939.722109726132;8046.316063134465;8943.776405589912;10949.233553651167;11378.43107844621;11680.824787628362;11718.296902185895 14:04:14 INFO - PROCESS | 1234 | |3;mootools.html;1288.368699337325;1397.5038861204273;1416.4475854416773;1426.0730525158647;1435.5406269993603;6821.6930238139275;7571.469997100206;7704.268094531025;7808.843823123549;7964.800879978004;7790.805229869256;7860.882086768703;7863.764087077382;7871.488353257034;7884.80287992799;1741.9129043547834;1984.7915968823247;2018.606371757508;2021.7270668459716;2024.1903238704488;1777.324616645673;1974.1511150205479;2022.423609271358;2029.22889302065;2039.775624681284;2018.788027257139;2027.2803154880025;2031.5327474680832;2037.5619241862996;2038.4801875521796;1698.9490315290561;1916.425072478258;1947.065408603871;1962.4995626115392;1964.9017549122486;1757.6045389787323;1948.814862588066;2005.037581960667;2006.78928712486;2007.9196832126843;7572.204918483535;7678.385729141655;7687.192844751276;7869.66215743325;7887.211278872067;4629.259318509023;4754.405699287589;4758.238681810928;4761.309610106515;4765.8570242892765;2808.5646724757694;2978.5829983802287;3005.0984704588536;3053.0688140117254;3068.7238148566735;1220.8718084601167;1262.4003598290738;1284.4284293489402;1309.9738005239938;1368.1790925444752;10197.082262596401;10416.791664166674;10426.791464170674;10449.84325235122;10463.581456741722;3812.961870381316;3860.1507668312925;3934.6195551691385;3952.3281042222893;4015.31739604268;8572.399932004824;8709.346798990102;8723.956380218058;8728.78178045554;8755.737327880173;4602.631789456902;4616.792244349012;4621.4223222097235;4626.32918226862;4628.305754136906;4213.2837417639075;4320.308750599889;4352.608265256142;4356.6732495063;4360.41134446852;3290.0952238134228;3315.834208289576;3352.6479719629574;3375.814330211813;3378.7634865559667;975.5951280218771;995.9402435853872;998.9200863930868;1005.1606908231709;1010.9191264698953;939.7979434421633;945.1021529546821;951.2104952889085;956.4500412262977;984.6602921991902;4937.506249375034;5170.689758614495;5392.946070539245;5438.483344082306;5539.279893613885;588.3293045927646;589.2016317889198;596.6151832068379;600.9338972712998;602.7890238416607;3432.6910578048096;3788.412796016622;3793.79134147619;3820.2741479119436;3914.080191155076;9186.356955013085;9347.719568412958;9409.341346105706;9421.576029078571;9424.198943089777;8726.171013753683;8917.955410222907;9099.636014559343;9203.585838637144;9392.483413412192;7142.071530700976;7203.315685009916;7344.228855970153;7355.963220183865;7361.1166660001145;5058.848234552969;5133.743312834418;5204.453532379046;5236.764345604533;5428.864278393072;8250.54621995796;8278.751637450887;8374.665013399395;8442.704505342284;8537.274331681629;8463.19599638058;8468.66125354979;8489.4481858679;8497.065322814362;8582.313414926915;6692.665366731547;6889.862202755817;6893.620850853251;6901.689423976033;6902.965485172542;7149.606771627402;7368.778936631909;7416.44376671741;7422.777316680508;7430.7027718892805;6871.759488417882;6925.896111558432;6940.063091482585;6967.407770339578;6989;6620.238672552526;6637.53537252407;6687.230968438497;6701.865962680621;6702.19573651165;5837.211976383134;5903.940960590339;5915.08316210988;5915.40845915405;5917.053271476543;3814.275287695329;4485.37204791323;4504.459464864237;4515.1195516873895;4649.628689368379;4655.743934083658;4716.853831506588;4741.525847415231;4835.443923948791;4990.5508504234795 14:04:14 INFO - PROCESS | 1234 | |4;prototype.html;382.00297224244736;390.16115351993216;390.42022596444275;396.11270753512105;402.027094431476;5120.8975820483565;5147.948520514794;5158.81944131955;5172.551685528048;5191.662541934772;4921.63087768417;5060.115734031603;5110.514501122397;5118.7952481900775;5128.333316668837;3979;3996.3206254936586;4006.479157709494;4008.617027125637;4014.2172276406186;4023.7786921719294;4028.133951200491;4028.7784171870535;4030.355143177092;4031.8992025199314;3899.493065901429;3940.940885886714;3970.2059588082498;3984.9601503984877;4015.277250094982;3940.8817735467983;3951.8616848410456;3958.8614398496056;3959.722819402643;3965.790433917654;3455.36075825973;3502.807345595991;3502.859885604573;3515.191505953645;3547;4902.2156454967035;4939.199952507717;4965.205567109281;5063.518965698253;5096.439391666913;4563.726176429424;4574.656900732459;4576.290674945389;4596.586307232332;4613.677042607019;5345.492178243061;5370.8184199476445;5386.879037536288;5405.972970135163;5423.837284881421;3818.9427158592644;3913.5890731473355;3924.725269231155;3927.6661483773914;3933.645971862546;5331.280277162611;5429.619926605139;5430.212619170198;5516.282883225155;5598.664080155204;3970.166265084307;3974.5627980922077;3984.183242435294;3989.3018721723583;3991.3015222335976;4030.536488303824;4030.9596904031173;4047.251258517184;4051.2910240708347;4069.2268468990796;4327.675424343187;4355.477342718894;4435.689501734848;4611.169989401873;4643.605293550086;5385.353757549041;5401.216823560561;5440.3743569489225;5442.210879422461;5449.155380915964;1371.9519816806564;1375.7661197596433;1393.9303034848217;1397.5318268380004;1398.7622104242303;901.340637572919;940.47333493244;953.5470651440648;955.2930831184873;963.1620490173594;5036.834161310599;5057.735566108473;5065.620078494054;5072;5075.771590278447;5367.604422850087;5378.435264297193;5379.973100134553;5383.596230282745;5393.514583687408;4867.488913664085;5052.747362631853;5062.924056139161;5083.313752643419;5248.845254044104;2858.8856445742354;2860.828350298989;2861.7138286171216;2864.8683769911186;2865.653143022776;5552.972235138798;5579.553635709215;5581.888362232733;5605.243292155588;5616.528727359949;5330.520253177233;5537.861553461115;5665.716714164358;5666.716664166775;5679;5718.885622287531;5741.712914354265;5744.339400968942;5746.712664366765;5747.224124743273;5369.0069134199275;5496.560275177896;5546.557894947262;5600.523955463831;5610.13042978339;5446.645968012067;5478.589105817;5531.612787104864;5553.6390134641115;5565.666060036411;5416.647917885325;5542.750576223999;5573.804916827891;5709.8287051388525;5748.655080695172;3961.5246170458577;4021.1354558770004;4030.314846476165;4054;4062.4921884764403;5521.889562208814;5557.971340460784;5578.776848925998;5581.972090139523;5599.160125981135;5204.245384419314;5353.848922481685;5360.597955153457;5385.596080293915;5440.905251478289;5377.247185394126;5390.811321603725;5398.217258497417;5431.37539182999;5433.2665090213895;5405.351357837085;5412.675239485643;5417.133258678593;5422.62041657096;5425.782968681208;5183.92224116631;5362.570994320367;5385.757640906247;5446.400895901527;5460.863478413072;5285.709285989307;5299.311089558333;5301.231321458444;5341.225522299167;5363.329583802025 14:04:14 INFO - PROCESS | 1234 | |5;yui.html;736.1350413264416;770.2413123073771;804.6298702596805;829.6515463505327;839.0895877972399;15604.063756174637;16336.509904702849;16356.509304720863;16368.345266189368;16413.83586164138;14794.926025369858;15072.773908391355;15085.924570377132;15120.773188402183;15146.697066058672;760.0157795654627;779.8206412525122;796.5021861336664;797.7247849491945;807.007380921467;760.8212070163507;761.025886864814;761.3376362564549;761.6077719974218;764.109812068939;833.6457005772553;839.1692224697535;841.1420351241729;841.7727213652311;841.9452735572199;830.7756905635445;831.8544254755394;832.3216578488543;832.4589017138878;832.9458585191943;602.3584882100549;604.045607939459;605.0168476226132;605.2978544887909;606.0273261415452;14530.782038269434;14551.3451894665;14559.854401456061;15207.163606001666;16024.35902563896;14226.359813808403;14835.925820370938;14876.479323223744;14976.925115374463;14984.325705343286;11800.822987655192;12137.271763694207;12262.754744905142;12401.813972790414;12453.501859925593;3628.2017956049626;3654.6162652921325;3658.6890114340313;3795.734298599099;3802.068493219151;73514.26485735127;73610.63194684045;75104.62447687726;75660.62169689171;75859;10568.735781605368;10737.677869663923;10787.946060269649;10789.46052697362;11074.280171788809;21452.463688407915;21704;21705;21709.56580868374;21744;6779.966100169468;6922.619255940871;7028.2620324866175;7047.964760176269;7118.359347658736;3920.2159568086495;3937.03542632057;3964.246793109358;3964.623360780721;3990.082281075369;3338.849751761177;3428.6571342866014;3462.2556150427276;3462.4113900636953;3474.9305013900084;554.8252300525321;557.9804706835241;567.5884983387011;570.1874828369639;572.5190839694593;575.0856138739424;588.5762251179144;603.6649659439054;645.6190847399963;710.3784188834769;10077.848832267522;10568.841467377995;10570;10620.256582039183;10631.362118272928;601.9729112189962;607.6657838188978;647.8380404898776;672.6233309346782;754.8301632132836;4856.17445034345;4954.567383011601;4995.500449955048;5037.06073161828;5044.54599086084;77318.6134069326;77945.22054779493;79126.20873791304;79184.20815791884;79631.60184199041;66569;66891;67777.3222267771;67830.98253526201;67985;59773.643007828374;59934;60262.39737602568;60497;60743.39256607466;9099.954500227457;9489.098535639103;9491.525423728925;9542.093501117382;9544.665936692183;40625.593744063364;40937.38593921034;41188.58811411847;41601.79199103985;42299;47394.76302618465;52491.47508524866;53322;53427.19859202193;53495.46504534905;11230;11854.881451185378;12323.568675096329;12621.368931553201;13217.933910330772;14191.716165676835;14336;14394.568162955127;14863.032816300438;15197.6200594986;13979.440822367398;14036.719265614427;14484.565463036126;14645.92677036608;15465.61335966565;12714;13499.797503037265;13565.79651305251;13843.307834608027;13989.860101399263;13756.793648095487;14321.928390358398;14404.135751854923;15116.470923517623;15118.697626047198;10444.634437794944;11461.885381146081;11692.714547045296;11760.882391176321;12437.751244974868;9728.659496917573;9928.702138935843;10213.74465638365;10832.566697332019;11055 14:04:14 INFO - PROCESS | 1234 | __end_tp_report 14:04:14 INFO - PROCESS | 1234 | __start_cc_report 14:04:14 INFO - PROCESS | 1234 | _x_x_mozilla_cycle_collect,2493 14:04:14 INFO - PROCESS | 1234 | __end_cc_report 14:04:14 INFO - PROCESS | 1234 | __startTimestamp1487369054873__endTimestamp 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | ------- Summary: start ------- 14:04:14 INFO - PROCESS | 1234 | Number of tests: 6 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | [#0] dojo.html Cycles:165 Average:5133.03 Median:5339.15 stddev:553.60 (10.4%) stddev-sans-first:531.56 14:04:14 INFO - PROCESS | 1234 | Values: 3088.9 3091.8 3124.9 3131.9 3165.0 5155.2 5191.3 5201.4 5203.3 5257.2 5148.1 5155.1 5158.8 5162.9 5229.2 4383.8 4591.1 4714.0 4746.7 4770.1 4526.9 4548.8 4703.5 4722.4 4737.2 4587.8 4604.1 4606.6 4660.2 4681.3 4696.1 4700.2 4708.8 4737.5 4765.0 4651.3 4685.2 4698.9 4706.5 4764.0 5009.5 5028.7 5046.6 5078.1 5214.0 5384.4 5420.7 5432.9 5433.3 5433.6 5547.3 5560.2 5564.3 5566.9 5568.2 5157.8 5158.5 5165.9 5167.3 5173.7 5542.9 5616.7 5625.5 5628.5 5632.4 5312.9 5338.7 5338.7 5418.6 5420.9 5477.5 5494.8 5506.4 5507.3 5510.2 5341.9 5379.0 5387.5 5387.9 5438.6 5438.5 5444.9 5481.0 5506.8 5596.9 4275.7 4319.8 4320.5 4350.7 4355.9 3879.5 3903.4 3914.7 3920.4 3922.1 5075.0 5116.9 5117.0 5176.5 5212.8 5495.2 5505.8 5509.4 5527.8 5576.0 5286.6 5294.8 5303.5 5303.6 5303.9 4718.2 4777.9 4788.0 4788.4 4828.2 5696.9 5704.6 5725.1 5751.3 5763.9 5437.7 5462.5 5465.3 5527.5 5539.4 5664.6 5689.2 5692.7 5697.2 5698.3 5425.1 5485.8 5535.8 5538.5 5538.9 5512.8 5556.1 5556.2 5563.3 5601.5 5449.0 5449.5 5449.7 5454.0 5500.8 5303.7 5347.5 5349.7 5403.2 5443.8 5312.4 5415.0 5416.2 5423.2 5424.2 5418.1 5458.2 5471.2 5529.7 5540.7 5236.6 5338.9 5339.1 5349.6 5377.0 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | [#1] ext.html Cycles:180 Average:54347.62 Median:353119.00 stddev:104481.38 (29.6%) stddev-sans-first:104696.45 14:04:14 INFO - PROCESS | 1234 | Values: 566.9 586.3 586.4 597.5 602.1 29705.3 41150.6 43496.8 43538.0 47040.3 45304.3 45725.5 45731.8 45761.5 45806.8 9083.9 9253.6 9272.7 9280.5 9284.3 7412.6 7527.1 10874.4 11181.7 11208.2 10815.9 10905.2 10906.9 10908.5 10910.3 10891.5 10906.6 10914.8 10921.8 10921.9 10191.0 10268.8 10319.5 10330.5 10332.5 41696.6 41955.6 41965.0 42045.4 42046.6 3739.5 3744.9 3773.5 3793.7 3816.7 26917.7 28746.7 29122.7 29219.4 29429.1 3669.3 3729.8 3814.3 3830.9 3928.9 298410.0 302250.0 302401.0 302599.0 304125.0 43864.0 51381.7 51846.7 51869.7 51945.7 43325.6 53310.7 54824.5 54825.0 60363.4 15560.4 15646.3 15771.4 15782.6 15794.6 12133.4 12164.7 12206.1 12219.3 12220.6 16791.5 16988.7 17010.2 17097.9 17110.7 6645.5 6676.6 6765.9 6822.5 6862.9 885.1 895.6 895.9 906.5 919.6 30078.9 31255.8 31291.4 31587.8 31801.5 1045.3 1061.4 1063.3 1068.6 1225.6 15172.6 15929.8 16142.1 16300.5 16357.3 498043.0 501244.0 502125.0 503177.0 503844.0 60136.0 60720.7 60730.0 60768.4 60898.7 1307.9 1376.7 1388.1 1390.4 1395.7 28341.7 30289.5 30394.5 30781.3 30856.4 50706.7 51201.7 51343.5 51347.7 51371.7 351042.0 352689.0 353031.0 353207.0 353972.0 23320.2 23691.3 25244.7 26850.7 27415.2 45609.8 45777.0 45799.3 46515.0 46553.2 34319.8 34702.7 35064.5 38750.2 39066.0 35298.8 35351.5 35608.8 35694.5 35854.6 35469.6 35765.8 36392.8 36557.1 36844.3 30207.0 32093.0 32161.2 32190.9 32599.2 24242.4 24461.9 24639.9 24876.8 24951.3 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | [#2] jquery.html Cycles:180 Average:32966.09 Median:34486.83 stddev:91745.36 (266.0%) stddev-sans-first:91970.17 14:04:14 INFO - PROCESS | 1234 | Values: 409.4 412.5 420.0 424.0 432.5 17485.7 18281.6 18992.4 20171.3 20171.9 20031.3 20242.9 20628.0 20867.5 20913.7 5572.8 5995.2 6027.4 6041.4 6042.0 5849.0 5939.7 5941.3 5945.7 7231.2 7514.7 7717.6 7718.3 7732.9 7745.7 7318.6 7603.0 7642.8 7662.7 7724.2 7207.5 7406.2 7448.4 7462.6 7465.3 25833.2 25871.4 26440.2 26457.9 26782.9 2835.4 2920.9 2922.0 2965.5 2983.4 10420.5 15374.8 15529.9 15702.1 16024.2 1912.2 1967.9 1973.0 1988.2 2008.6 102667.0 104173.0 104269.0 105337.0 106769.0 18546.8 18595.6 18813.6 18921.7 18980.8 34365.8 34607.8 34617.7 34692.8 34832.3 10088.9 10247.3 10289.8 10362.3 10597.9 8381.7 8413.7 8478.8 8610.3 8649.9 9492.7 9628.0 9656.8 9675.0 9732.9 3725.9 3800.5 3929.5 3959.4 3960.9 820.9 855.0 863.5 872.6 879.6 19476.7 19539.8 20220.7 20240.0 20334.7 848.4 851.7 867.2 871.1 874.3 10436.4 10946.3 10981.2 11073.7 11091.2 548247.0 548671.0 549326.0 551778.0 584428.0 72399.6 76569.2 100250.5 104429.0 107683.0 1017.3 1049.8 1065.1 1070.4 1075.9 16377.8 16569.6 16732.0 17204.0 17823.0 42777.3 43374.0 43407.3 44700.0 44913.8 58084.7 59478.4 59711.7 60966.7 61545.4 7914.9 7985.3 8699.5 8806.2 9190.6 12746.1 12871.8 12944.8 13262.3 13459.7 5917.8 6737.6 10920.9 11093.9 11547.8 11190.2 11214.2 11341.4 11369.6 11407.9 11404.1 11808.0 11816.7 11821.8 11883.6 7846.5 7869.1 7870.2 7939.7 8046.3 8943.8 10949.2 11378.4 11680.8 11718.3 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | [#3] mootools.html Cycles:180 Average:5026.27 Median:5187.57 stddev:2805.84 (54.1%) stddev-sans-first:2799.65 14:04:14 INFO - PROCESS | 1234 | Values: 1288.4 1397.5 1416.4 1426.1 1435.5 6821.7 7571.5 7704.3 7808.8 7964.8 7790.8 7860.9 7863.8 7871.5 7884.8 1741.9 1984.8 2018.6 2021.7 2024.2 1777.3 1974.2 2022.4 2029.2 2039.8 2018.8 2027.3 2031.5 2037.6 2038.5 1698.9 1916.4 1947.1 1962.5 1964.9 1757.6 1948.8 2005.0 2006.8 2007.9 7572.2 7678.4 7687.2 7869.7 7887.2 4629.3 4754.4 4758.2 4761.3 4765.9 2808.6 2978.6 3005.1 3053.1 3068.7 1220.9 1262.4 1284.4 1310.0 1368.2 10197.1 10416.8 10426.8 10449.8 10463.6 3813.0 3860.2 3934.6 3952.3 4015.3 8572.4 8709.3 8724.0 8728.8 8755.7 4602.6 4616.8 4621.4 4626.3 4628.3 4213.3 4320.3 4352.6 4356.7 4360.4 3290.1 3315.8 3352.6 3375.8 3378.8 975.6 995.9 998.9 1005.2 1010.9 939.8 945.1 951.2 956.5 984.7 4937.5 5170.7 5392.9 5438.5 5539.3 588.3 589.2 596.6 600.9 602.8 3432.7 3788.4 3793.8 3820.3 3914.1 9186.4 9347.7 9409.3 9421.6 9424.2 8726.2 8918.0 9099.6 9203.6 9392.5 7142.1 7203.3 7344.2 7356.0 7361.1 5058.8 5133.7 5204.5 5236.8 5428.9 8250.5 8278.8 8374.7 8442.7 8537.3 8463.2 8468.7 8489.4 8497.1 8582.3 6692.7 6889.9 6893.6 6901.7 6903.0 7149.6 7368.8 7416.4 7422.8 7430.7 6871.8 6925.9 6940.1 6967.4 6989.0 6620.2 6637.5 6687.2 6701.9 6702.2 5837.2 5903.9 5915.1 5915.4 5917.1 3814.3 4485.4 4504.5 4515.1 4649.6 4655.7 4716.9 4741.5 4835.4 4990.6 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | [#4] prototype.html Cycles:180 Average:4528.25 Median:5153.38 stddev:1317.90 (25.6%) stddev-sans-first:1284.33 14:04:14 INFO - PROCESS | 1234 | Values: 382.0 390.2 390.4 396.1 402.0 5120.9 5147.9 5158.8 5172.6 5191.7 4921.6 5060.1 5110.5 5118.8 5128.3 3979.0 3996.3 4006.5 4008.6 4014.2 4023.8 4028.1 4028.8 4030.4 4031.9 3899.5 3940.9 3970.2 3985.0 4015.3 3940.9 3951.9 3958.9 3959.7 3965.8 3455.4 3502.8 3502.9 3515.2 3547.0 4902.2 4939.2 4965.2 5063.5 5096.4 4563.7 4574.7 4576.3 4596.6 4613.7 5345.5 5370.8 5386.9 5406.0 5423.8 3818.9 3913.6 3924.7 3927.7 3933.6 5331.3 5429.6 5430.2 5516.3 5598.7 3970.2 3974.6 3984.2 3989.3 3991.3 4030.5 4031.0 4047.3 4051.3 4069.2 4327.7 4355.5 4435.7 4611.2 4643.6 5385.4 5401.2 5440.4 5442.2 5449.2 1372.0 1375.8 1393.9 1397.5 1398.8 901.3 940.5 953.5 955.3 963.2 5036.8 5057.7 5065.6 5072.0 5075.8 5367.6 5378.4 5380.0 5383.6 5393.5 4867.5 5052.7 5062.9 5083.3 5248.8 2858.9 2860.8 2861.7 2864.9 2865.7 5553.0 5579.6 5581.9 5605.2 5616.5 5330.5 5537.9 5665.7 5666.7 5679.0 5718.9 5741.7 5744.3 5746.7 5747.2 5369.0 5496.6 5546.6 5600.5 5610.1 5446.6 5478.6 5531.6 5553.6 5565.7 5416.6 5542.8 5573.8 5709.8 5748.7 3961.5 4021.1 4030.3 4054.0 4062.5 5521.9 5558.0 5578.8 5582.0 5599.2 5204.2 5353.8 5360.6 5385.6 5440.9 5377.2 5390.8 5398.2 5431.4 5433.3 5405.4 5412.7 5417.1 5422.6 5425.8 5183.9 5362.6 5385.8 5446.4 5460.9 5285.7 5299.3 5301.2 5341.2 5363.3 14:04:14 INFO - PROCESS | 1234 | 14:04:14 INFO - PROCESS | 1234 | [#5] yui.html Cycles:180 Average:17246.56 Median:41395.19 stddev:21699.79 (52.4%) stddev-sans-first:21725.25 14:04:14 INFO - PROCESS | 1234 | Values: 736.1 770.2 804.6 829.7 839.1 15604.1 16336.5 16356.5 16368.3 16413.8 14794.9 15072.8 15085.9 15120.8 15146.7 760.0 779.8 796.5 797.7 807.0 760.8 761.0 761.3 761.6 764.1 833.6 839.2 841.1 841.8 841.9 830.8 831.9 832.3 832.5 832.9 602.4 604.0 605.0 605.3 606.0 14530.8 14551.3 14559.9 15207.2 16024.4 14226.4 14835.9 14876.5 14976.9 14984.3 11800.8 12137.3 12262.8 12401.8 12453.5 3628.2 3654.6 3658.7 3795.7 3802.1 73514.3 73610.6 75104.6 75660.6 75859.0 10568.7 10737.7 10787.9 10789.5 11074.3 21452.5 21704.0 21705.0 21709.6 21744.0 6780.0 6922.6 7028.3 7048.0 7118.4 3920.2 3937.0 3964.2 3964.6 3990.1 3338.8 3428.7 3462.3 3462.4 3474.9 554.8 558.0 567.6 570.2 572.5 575.1 588.6 603.7 645.6 710.4 10077.8 10568.8 10570.0 10620.3 10631.4 602.0 607.7 647.8 672.6 754.8 4856.2 4954.6 4995.5 5037.1 5044.5 77318.6 77945.2 79126.2 79184.2 79631.6 66569.0 66891.0 67777.3 67831.0 67985.0 59773.6 59934.0 60262.4 60497.0 60743.4 9100.0 9489.1 9491.5 9542.1 9544.7 40625.6 40937.4 41188.6 41601.8 42299.0 47394.8 52491.5 53322.0 53427.2 53495.5 11230.0 11854.9 12323.6 12621.4 13217.9 14191.7 14336.0 14394.6 14863.0 15197.6 13979.4 14036.7 14484.6 14645.9 15465.6 12714.0 13499.8 13565.8 13843.3 13989.9 13756.8 14321.9 14404.1 15116.5 15118.7 10444.6 11461.9 11692.7 11760.9 12437.8 9728.7 9928.7 10213.7 10832.6 11055.0 14:04:14 INFO - PROCESS | 1234 | -------- Summary: end -------- 14:04:14 INFO - PROCESS | 1234 | 14:04:15 INFO - TEST-INFO | 1234: exit 0 14:04:15 INFO - TEST-OK | dromaeo_css | took 1117020ms 14:04:15 INFO - TEST-START | kraken 14:04:15 INFO - operating with platform_type : linux_ 14:04:15 INFO - Initialising browser for kraken test... 14:04:15 INFO - TEST-INFO | started process 1568 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYeWNfQ/profile http://localhost:45915/getInfo.html) 14:04:18 INFO - PROCESS | 1568 | Iterating over profile /tmp/tmpYeWNfQ/profile 14:04:19 INFO - PROCESS | 1568 | __metrics Screen width/height:1600/1200 14:04:19 INFO - PROCESS | 1568 | colorDepth:24 14:04:19 INFO - PROCESS | 1568 | Browser inner width/height: 1024/697 14:04:19 INFO - PROCESS | 1568 | __metrics 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/minidumps = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/permissions.sqlite = Fri Feb 17 2017 14:04:16 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | Stat failure 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/places.sqlite = Fri Feb 17 2017 14:04:19 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/mimeTypes.rdf = Fri Feb 17 2017 14:04:19 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/key3.db = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/blocklist.xml = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/places.sqlite-shm = Fri Feb 17 2017 14:04:17 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/localstore.rdf = Fri Feb 17 2017 13:44:57 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/compatibility.ini = Fri Feb 17 2017 14:04:16 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/pluginreg.dat = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:19 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:19 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/cookies.sqlite = Fri Feb 17 2017 14:04:19 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/gmp = Fri Feb 17 2017 14:04:16 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/secmod.db = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/frequencyCap.json = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/safebrowsing = Fri Feb 17 2017 14:04:20 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/places.sqlite-wal = Fri Feb 17 2017 14:04:20 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/cache2 = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/directoryLinks.json = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/sessionCheckpoints.json = Fri Feb 17 2017 14:04:19 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/extensions.ini = Fri Feb 17 2017 14:04:16 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/crashes = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/.parentlock = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/extensions = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/content-prefs.sqlite = Fri Feb 17 2017 14:04:17 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/startupCache = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/extensions.json = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/bookmarkbackups = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/addons.json = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/thumbnails = Fri Feb 17 2017 14:04:18 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/cert8.db = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/user.js = Fri Feb 17 2017 14:04:15 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | No birth date. Using mtime. 14:04:20 INFO - PROCESS | 1568 | Using date: /tmp/tmpYeWNfQ/profile/prefs.js = Fri Feb 17 2017 13:44:57 GMT-0800 (PST) 14:04:20 INFO - PROCESS | 1568 | JavaScript error: resource://gre/modules/AsyncShutdown.jsm, line 692: Error: Phase "quit-application-granted" is finished, it is too late to register completion condition "SessionStore: flushing all windows" 14:04:20 INFO - PROCESS | 1568 | JavaScript error: resource://app/modules/BrowserUsageTelemetry.jsm, line 303: NS_ERROR_FAILURE: Component returned failure code: 0x80004005 (NS_ERROR_FAILURE) [nsIObserverService.removeObserver] 14:04:21 INFO - TEST-INFO | 1568: exit 0 14:04:21 INFO - Browser initialized. 14:04:21 INFO - Running cycle 1/1 for kraken test... 14:04:21 INFO - TEST-INFO | started process 1658 (/builds/slave/test/build/application/firefox/firefox -profile /tmp/tmpYeWNfQ/profile -tp file:/builds/slave/test/build/tests/talos/talos/tests/kraken/kraken.manifest.develop -tpchrome -tpnoisy -tpcycles 1 -tppagecycles 1) 14:04:33 INFO - PROCESS | 1658 | RSS: Main: 143048704 14:04:33 INFO - PROCESS | 1658 | 14:04:36 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=ai-astar (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-beat-detection) 14:04:37 INFO - PROCESS | 1658 | RSS: Main: 197885952 14:04:37 INFO - PROCESS | 1658 | 14:04:39 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-beat-detection (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-dft) 14:04:40 INFO - PROCESS | 1658 | RSS: Main: 177209344 14:04:40 INFO - PROCESS | 1658 | 14:04:43 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-dft (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-fft) 14:04:43 INFO - PROCESS | 1658 | RSS: Main: 258437120 14:04:43 INFO - PROCESS | 1658 | 14:04:46 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-fft (next: http://localhost:45915/tests/kraken/driver.html?testName=audio-oscillator) 14:04:46 INFO - PROCESS | 1658 | RSS: Main: 169988096 14:04:46 INFO - PROCESS | 1658 | 14:04:48 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=audio-oscillator (next: http://localhost:45915/tests/kraken/driver.html?testName=imaging-gaussian-blur) 14:04:49 INFO - PROCESS | 1658 | RSS: Main: 174383104 14:04:49 INFO - PROCESS | 1658 | 14:04:52 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=imaging-gaussian-blur (next: http://localhost:45915/tests/kraken/driver.html?testName=imaging-darkroom) 14:04:52 INFO - PROCESS | 1658 | RSS: Main: 213544960 14:04:52 INFO - PROCESS | 1658 | 14:04:56 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=imaging-darkroom (next: http://localhost:45915/tests/kraken/driver.html?testName=imaging-desaturate) 14:04:56 INFO - PROCESS | 1658 | RSS: Main: 213475328 14:04:56 INFO - PROCESS | 1658 | 14:04:59 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=imaging-desaturate (next: http://localhost:45915/tests/kraken/driver.html?testName=json-parse-financial) 14:05:00 INFO - PROCESS | 1658 | RSS: Main: 211984384 14:05:00 INFO - PROCESS | 1658 | 14:05:01 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=json-parse-financial (next: http://localhost:45915/tests/kraken/driver.html?testName=json-stringify-tinderbox) 14:05:02 INFO - PROCESS | 1658 | RSS: Main: 174264320 14:05:02 INFO - PROCESS | 1658 | 14:05:04 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=json-stringify-tinderbox (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-aes) 14:05:04 INFO - PROCESS | 1658 | RSS: Main: 181829632 14:05:04 INFO - PROCESS | 1658 | 14:05:06 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-aes (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-ccm) 14:05:07 INFO - PROCESS | 1658 | RSS: Main: 190529536 14:05:07 INFO - PROCESS | 1658 | 14:05:10 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-ccm (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2) 14:05:10 INFO - PROCESS | 1658 | RSS: Main: 200142848 14:05:10 INFO - PROCESS | 1658 | 14:05:13 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-pbkdf2 (next: http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative) 14:05:14 INFO - PROCESS | 1658 | RSS: Main: 196747264 14:05:14 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | Cycle 1(1): loaded http://localhost:45915/tests/kraken/driver.html?testName=stanford-crypto-sha256-iterative (next: http://localhost:45915/tests/kraken/driver.html?testName=ai-astar) 14:05:16 INFO - PROCESS | 1658 | RSS: Main: 248528896 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | __start_tp_report 14:05:16 INFO - PROCESS | 1658 | _x_x_mozilla_page_load 14:05:16 INFO - PROCESS | 1658 | _x_x_mozilla_page_load_details 14:05:16 INFO - PROCESS | 1658 | |i|pagename|runs| 14:05:16 INFO - PROCESS | 1658 | |0;ai-astar;111;113;115;113;115;115;115;114;114;114 14:05:16 INFO - PROCESS | 1658 | |1;audio-beat-detection;145;121;125;165;121;122;128;120;145;121 14:05:16 INFO - PROCESS | 1658 | |2;audio-dft;161;163;162;162;162;163;164;162;162;162 14:05:16 INFO - PROCESS | 1658 | |3;audio-fft;76;76;76;116;78;76;78;77;106;77 14:05:16 INFO - PROCESS | 1658 | |4;audio-oscillator;84;82;110;83;83;83;98;83;82;106 14:05:16 INFO - PROCESS | 1658 | |5;imaging-gaussian-blur;114;113;113;113;113;113;113;113;113;113 14:05:16 INFO - PROCESS | 1658 | |6;imaging-darkroom;147;147;148;147;147;147;148;147;147;146 14:05:16 INFO - PROCESS | 1658 | |7;imaging-desaturate;96;96;95;96;96;96;95;96;96;96 14:05:16 INFO - PROCESS | 1658 | |8;json-parse-financial;54;53;53;53;55;55;64;55;55;54 14:05:16 INFO - PROCESS | 1658 | |9;json-stringify-tinderbox;64;63;63;63;65;64;63;64;64;64 14:05:16 INFO - PROCESS | 1658 | |10;stanford-crypto-aes;84;84;86;84;84;84;88;91;84;86 14:05:16 INFO - PROCESS | 1658 | |11;stanford-crypto-ccm;143;133;135;130;130;137;142;133;171;146 14:05:16 INFO - PROCESS | 1658 | |12;stanford-crypto-pbkdf2;172;174;174;174;174;173;171;174;169;169 14:05:16 INFO - PROCESS | 1658 | |13;stanford-crypto-sha256-iterative;67;64;64;65;67;65;64;64;63;64 14:05:16 INFO - PROCESS | 1658 | __end_tp_report 14:05:16 INFO - PROCESS | 1658 | __start_cc_report 14:05:16 INFO - PROCESS | 1658 | _x_x_mozilla_cycle_collect,1745 14:05:16 INFO - PROCESS | 1658 | __end_cc_report 14:05:16 INFO - PROCESS | 1658 | __startTimestamp1487369116278__endTimestamp 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | ------- Summary: start ------- 14:05:16 INFO - PROCESS | 1658 | Number of tests: 14 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#0] ai-astar Cycles:10 Average:113.90 Median:114.50 stddev:1.29 (1.1%) stddev-sans-first:0.83 14:05:16 INFO - PROCESS | 1658 | Values: 111.0 113.0 115.0 113.0 115.0 115.0 115.0 114.0 114.0 114.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#1] audio-beat-detection Cycles:10 Average:131.30 Median:126.50 stddev:15.25 (12.1%) stddev-sans-first:15.35 14:05:16 INFO - PROCESS | 1658 | Values: 145.0 121.0 125.0 165.0 121.0 122.0 128.0 120.0 145.0 121.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#2] audio-dft Cycles:10 Average:162.30 Median:162.00 stddev:0.82 (0.5%) stddev-sans-first:0.73 14:05:16 INFO - PROCESS | 1658 | Values: 161.0 163.0 162.0 162.0 162.0 163.0 164.0 162.0 162.0 162.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#3] audio-fft Cycles:10 Average:83.60 Median:76.50 stddev:14.65 (19.2%) stddev-sans-first:15.28 14:05:16 INFO - PROCESS | 1658 | Values: 76.0 76.0 76.0 116.0 78.0 76.0 78.0 77.0 106.0 77.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#4] audio-oscillator Cycles:10 Average:89.40 Median:83.00 stddev:10.94 (13.2%) stddev-sans-first:11.42 14:05:16 INFO - PROCESS | 1658 | Values: 84.0 82.0 110.0 83.0 83.0 83.0 98.0 83.0 82.0 106.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#5] imaging-gaussian-blur Cycles:10 Average:113.10 Median:113.00 stddev:0.32 (0.3%) stddev-sans-first:0.00 14:05:16 INFO - PROCESS | 1658 | Values: 114.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 113.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#6] imaging-darkroom Cycles:10 Average:147.10 Median:147.00 stddev:0.57 (0.4%) stddev-sans-first:0.60 14:05:16 INFO - PROCESS | 1658 | Values: 147.0 147.0 148.0 147.0 147.0 147.0 148.0 147.0 147.0 146.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#7] imaging-desaturate Cycles:10 Average:95.80 Median:96.00 stddev:0.42 (0.4%) stddev-sans-first:0.44 14:05:16 INFO - PROCESS | 1658 | Values: 96.0 96.0 95.0 96.0 96.0 96.0 95.0 96.0 96.0 96.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#8] json-parse-financial Cycles:10 Average:55.10 Median:55.00 stddev:3.25 (5.9%) stddev-sans-first:3.42 14:05:16 INFO - PROCESS | 1658 | Values: 54.0 53.0 53.0 53.0 55.0 55.0 64.0 55.0 55.0 54.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#9] json-stringify-tinderbox Cycles:10 Average:63.70 Median:64.00 stddev:0.67 (1.1%) stddev-sans-first:0.71 14:05:16 INFO - PROCESS | 1658 | Values: 64.0 63.0 63.0 63.0 65.0 64.0 63.0 64.0 64.0 64.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#10] stanford-crypto-aes Cycles:10 Average:85.50 Median:85.00 stddev:2.37 (2.8%) stddev-sans-first:2.45 14:05:16 INFO - PROCESS | 1658 | Values: 84.0 84.0 86.0 84.0 84.0 84.0 88.0 91.0 84.0 86.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#11] stanford-crypto-ccm Cycles:10 Average:140.00 Median:139.50 stddev:12.21 (8.8%) stddev-sans-first:12.90 14:05:16 INFO - PROCESS | 1658 | Values: 143.0 133.0 135.0 130.0 130.0 137.0 142.0 133.0 171.0 146.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#12] stanford-crypto-pbkdf2 Cycles:10 Average:172.40 Median:174.00 stddev:2.07 (1.2%) stddev-sans-first:2.19 14:05:16 INFO - PROCESS | 1658 | Values: 172.0 174.0 174.0 174.0 174.0 173.0 171.0 174.0 169.0 169.0 14:05:16 INFO - PROCESS | 1658 | 14:05:16 INFO - PROCESS | 1658 | [#13] stanford-crypto-sha256-iterative Cycles:10 Average:64.70 Median:64.50 stddev:1.34 (2.1%) stddev-sans-first:1.13 14:05:16 INFO - PROCESS | 1658 | Values: 67.0 64.0 64.0 65.0 67.0 65.0 64.0 64.0 63.0 64.0 14:05:16 INFO - PROCESS | 1658 | -------- Summary: end -------- 14:05:16 INFO - PROCESS | 1658 | 14:05:17 INFO - TEST-INFO | 1658: exit 0 14:05:17 INFO - TEST-OK | kraken | took 61716ms 14:05:17 INFO - SUITE-END | took 1178s 14:05:17 INFO - Completed test suite (00:19:38) 14:05:17 INFO - javascript benchmark 14:05:17 INFO - PERFHERDER_DATA: {"framework": {"name": "talos"}, "suites": [{"extraOptions": [], "name": "dromaeo_css", "lowerIsBetter": false, "alertThreshold": 5.0, "value": 7036.548249845873, "subtests": [{"name": "dojo.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [3088.8764449422038, 3091.799033062851, 3124.906252812415, 3131.8747250109996, 3164.9683503164974, 5155.175171972485, 5191.325127733394, 5201.427842937273, 5203.271541984116, 5257.211418287259, 5148.124818780807, 5155.123628983072, 5158.7936482540745, 5162.870928226796, 5229.241759944805, 4383.802728877192, 4591.058832939256, 4714.010057887847, 4746.715197088169, 4770.117528257266, 4526.932096018562, 4548.818047278105, 4703.529647035286, 4722.433308003045, 4737.194676904935, 4587.793549290273, 4604.125216208927, 4606.631469482433, 4660.16117098922, 4681.297805329228, 4696.060787842411, 4700.2479603263655, 4708.811647534095, 4737.549932756418, 4764.976175119137, 4651.3255577941345, 4685.2269375553, 4698.882527936795, 4706.482286948433, 4764.0, 5009.474005229435, 5028.748562571856, 5046.646734728571, 5078.060558796635, 5214.0, 5384.3538775346815, 5420.7018613976215, 5432.891342173134, 5433.320834895638, 5433.565314774809, 5547.306586676666, 5560.221568980346, 5564.33228012637, 5566.916496252519, 5568.248286481355, 5157.819476318311, 5158.484151584849, 5165.870853228662, 5167.250748641426, 5173.689578625257, 5542.889142217133, 5616.663000219918, 5625.46558076982, 5628.549716022709, 5632.352279487911, 5312.867178320573, 5338.706371149624, 5338.733063346817, 5418.647787893774, 5420.891582168335, 5477.45225477457, 5494.80768173112, 5506.394296627368, 5507.311586051744, 5510.228568000483, 5341.866453338697, 5379.0, 5387.515123638891, 5387.865303367447, 5438.646487978269, 5438.483344082306, 5444.91832622511, 5481.0, 5506.752196151183, 5596.888062238732, 4275.700700950966, 4319.827206911751, 4320.524742278347, 4350.695451318377, 4355.869323920288, 3879.476270703475, 3903.375459926398, 3914.72596918213, 3920.4315374270573, 3922.0783115967724, 5075.0, 5116.948830511647, 5117.02776472469, 5176.456472070454, 5212.765425555859, 5495.230667706524, 5505.807296744595, 5509.449055094458, 5527.806526771544, 5575.972120139373, 5286.60350473716, 5294.7617357219015, 5303.549198318186, 5303.602229832778, 5303.946960530346, 4718.174319494075, 4777.904441911142, 4787.952120478751, 4788.353572267699, 4828.227483602606, 5696.914546281809, 5704.629199102045, 5725.083986562046, 5751.281089863767, 5763.9135412968835, 5437.728113594226, 5462.481064298885, 5465.344158700981, 5527.53015993645, 5539.362973258127, 5664.631798933056, 5689.203511508475, 5692.743826527733, 5697.173909783141, 5698.31620205578, 5425.13197888336, 5485.753141108739, 5535.750891209986, 5538.501534861882, 5538.944610553843, 5512.834614961557, 5556.138798486241, 5556.16657501378, 5563.276774019513, 5601.523870470893, 5448.972755136199, 5449.536789372948, 5449.7002664852225, 5453.972730136324, 5500.80747173847, 5303.734813259399, 5347.518723314764, 5349.732513374393, 5403.216533602685, 5443.782248710166, 5312.389075256395, 5414.972925135349, 5416.214648875812, 5423.186522021729, 5424.186372044225, 5418.133098704188, 5458.208559758733, 5471.151971444432, 5529.723513824373, 5540.722963851872, 5236.6072544560075, 5338.919916201337, 5339.145736682268, 5349.598780091586, 5377.0], "value": 5098.273926613364, "unit": "score"}, {"name": "ext.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [566.9007923613368, 586.2935163128433, 586.4106572894243, 597.4921316880651, 602.0848310567934, 29705.25736856577, 41150.58849411505, 43496.782516087456, 43538.0, 47040.29439558401, 45304.3204351935, 45725.54274457254, 45731.77134114325, 45761.54238457615, 45806.7709661452, 9083.863742043857, 9253.58358873849, 9272.67545635903, 9280.535973201348, 9284.303677224201, 7412.55524668519, 7527.0967483902, 10874.401907895062, 11181.664550063511, 11208.21542492026, 10815.891841081568, 10905.18211134164, 10906.890931090707, 10908.454577271144, 10910.345379277229, 10891.455427228646, 10906.61826836065, 10914.781704365947, 10921.836172457419, 10921.945390273077, 10191.0, 10268.845967310497, 10319.484025798754, 10330.535125919352, 10332.535035923402, 41696.58303416958, 41955.58044419578, 41965.0, 42045.36931946023, 42046.579534204575, 3739.4577786221116, 3744.906377340561, 3773.47171396002, 3793.7344385892998, 3816.732828701992, 26917.730822691916, 28746.71253287461, 29122.70877291221, 29219.41561168786, 29429.11712648624, 3669.3028324618235, 3729.7948612826285, 3814.275287695329, 3830.9042273943096, 3928.9017774555577, 298410.0, 302250.0, 302401.0, 302599.0, 304125.0, 43864.0, 51381.743091284305, 51846.74076629668, 51869.74065129726, 51945.74027129916, 43325.566744332784, 53310.73344633252, 54824.45175548273, 54824.95388260972, 60363.39636603578, 15560.377584896478, 15646.295916683777, 15771.44799931997, 15782.605434864221, 15794.60513487149, 12133.393330333449, 12164.695882603006, 12206.08454365926, 12219.26684398939, 12220.633380998584, 16791.496255112364, 16988.74516882248, 17010.19331810262, 17097.922373972557, 17110.657786844193, 6645.468362530986, 6676.566023208476, 6765.932340676531, 6822.488313376417, 6862.9313706863295, 885.1148851148852, 895.6193617712446, 895.9372843900994, 906.4515967839362, 919.6367434863325, 30078.941373401594, 31255.843720781704, 31291.374172516877, 31587.839526537507, 31801.52297715536, 1045.2526443592715, 1061.400308825511, 1063.3247887591435, 1068.5618896252654, 1225.559967038726, 15172.620684482756, 15929.761053584205, 16142.112183829768, 16300.505238314852, 16357.345706171858, 498043.0, 501244.0, 502125.0, 503177.0, 503844.0, 60136.0, 60720.696396517735, 60730.0, 60768.39231607716, 60898.695506522185, 1307.9280639564724, 1376.6695992961618, 1388.125480946997, 1390.3604342002793, 1395.6720170759863, 28341.71658283473, 30289.545656815608, 30394.544081838347, 30781.272447154683, 30856.382872342878, 50706.74646626743, 51201.74399127981, 51343.48656513536, 51347.74326128345, 51371.74314128405, 351042.0, 352689.0, 353031.0, 353207.0, 353972.0, 23320.183793567143, 23691.28926132219, 25244.74755252424, 26850.731492684823, 27415.17754467369, 45609.77195114136, 45777.0, 45799.313010304206, 46515.0, 46553.22382299755, 34319.828400857834, 34702.65297346994, 35064.47403288902, 38750.224995500495, 39066.0, 35298.823505882305, 35351.469727954616, 35608.82195589005, 35694.4645830318, 35854.64145358513, 35469.645303547666, 35765.82117089502, 36392.81803590965, 36557.08607284839, 36844.26311473702, 30207.0, 32093.0, 32161.195970100936, 32190.873319433707, 32599.18502037468, 24242.393940151636, 24461.877690611433, 24639.8768006166, 24876.751232487444, 24951.251462456155], "value": 19256.817891105, "unit": "score"}, {"name": "jquery.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [409.4104489535067, 412.4885142423392, 419.9895002624934, 423.97668128252946, 432.5199029077723, 17485.7377139343, 18281.63436731266, 18992.430227093173, 20171.29400470984, 20171.899140504294, 20031.298904538344, 20242.89878550605, 20628.0, 20867.478313042182, 20913.686294705592, 5572.804951826687, 5995.1906492623375, 6027.397260273981, 6041.395860413945, 6041.9697901510435, 5849.0, 5939.67331796751, 5941.257342832146, 5945.672987985659, 7231.240719724432, 7514.661840217176, 7717.575533345664, 7718.343940765042, 7732.922670773305, 7745.728899488519, 7318.560886346836, 7602.961985190094, 7642.770716878503, 7662.73180438682, 7724.150343462215, 7207.45944054198, 7406.185319614838, 7448.441366897504, 7462.626868656545, 7465.328120469129, 25833.225003249932, 25871.353216169748, 26440.206793796217, 26457.867710661518, 26782.86608566964, 2835.4187391584674, 2920.8831646734106, 2921.956170657442, 2965.4958657028355, 2983.4331477019296, 10420.531076101593, 15374.846251537454, 15529.907657578908, 15702.136382498958, 16024.157221919511, 1912.2159914435153, 1967.9311224107087, 1972.9506762330914, 1988.2146552111835, 2008.6284037452972, 102667.0, 104173.0, 104269.0, 105337.0, 106769.0, 18546.81453185451, 18595.628087438174, 18813.623727525646, 18921.7161742574, 18980.810191897905, 34365.828170858986, 34607.82696086554, 34617.653823461944, 34692.826535867665, 34832.30335393278, 10088.949555252324, 10247.282690211614, 10289.794204115875, 10362.326448780805, 10597.9470102649, 8381.664733410595, 8413.747587572383, 8478.788030299293, 8610.311175105977, 8649.870251946226, 9492.667756628485, 9628.0, 9656.806863862683, 9675.0, 9732.90267097334, 3725.9441108383394, 3800.4869342638945, 3929.548101968253, 3959.366501359769, 3960.9207815844097, 820.8973878265217, 854.9786255343666, 863.456022705704, 872.6291326186346, 879.5778026547293, 19476.70784938227, 19539.80460195408, 20220.69668954967, 20240.0, 20334.694979575317, 848.3679658654219, 851.67210623909, 867.2324992381788, 871.1419252036866, 874.3486102853287, 10436.42599657011, 10946.288491248044, 10981.17641176915, 11073.667789966314, 11091.22361434708, 548247.0, 548671.0, 549326.0, 551778.0, 584428.0, 72399.6380018107, 76569.23430765621, 100250.4987475058, 104429.0, 107683.0, 1017.308230403318, 1049.7533079726259, 1065.1372388365294, 1070.373831308693, 1075.9462026898623, 16377.836221638108, 16569.585760356087, 16732.0, 17204.0, 17823.0, 42777.30115200194, 43374.0, 43407.34888976605, 44700.0, 44913.77543112264, 58084.709576451845, 59478.40521594902, 59711.701441492514, 60966.69516652566, 61545.38454615397, 7914.920850791418, 7985.321247694011, 8699.478031318142, 8806.163414475615, 9190.58642361082, 12746.10777245584, 12871.806922895978, 12944.8058279124, 13262.270575118075, 13459.730805384033, 5917.822465326047, 6737.59574425536, 10920.945395272973, 11093.889061109609, 11547.769044619228, 11190.160737944523, 11214.215004949576, 11341.432928353384, 11369.602063927723, 11407.885921140682, 11404.087672986307, 11808.0, 11816.70458238551, 11821.82267266009, 11883.58407455735, 7846.450748447553, 7869.0950540688555, 7870.212978702084, 7939.722109726132, 8046.316063134465, 8943.776405589912, 10949.233553651167, 11378.43107844621, 11680.824787628362, 11718.296902185895], "value": 10506.301913308056, "unit": "score"}, {"name": "mootools.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [1288.368699337325, 1397.5038861204273, 1416.4475854416773, 1426.0730525158647, 1435.5406269993603, 6821.6930238139275, 7571.469997100206, 7704.268094531025, 7808.843823123549, 7964.800879978004, 7790.805229869256, 7860.882086768703, 7863.764087077382, 7871.488353257034, 7884.80287992799, 1741.9129043547834, 1984.7915968823247, 2018.606371757508, 2021.7270668459716, 2024.1903238704488, 1777.324616645673, 1974.1511150205479, 2022.423609271358, 2029.22889302065, 2039.775624681284, 2018.788027257139, 2027.2803154880025, 2031.5327474680832, 2037.5619241862996, 2038.4801875521796, 1698.9490315290561, 1916.425072478258, 1947.065408603871, 1962.4995626115392, 1964.9017549122486, 1757.6045389787323, 1948.814862588066, 2005.037581960667, 2006.78928712486, 2007.9196832126843, 7572.204918483535, 7678.385729141655, 7687.192844751276, 7869.66215743325, 7887.211278872067, 4629.259318509023, 4754.405699287589, 4758.238681810928, 4761.309610106515, 4765.8570242892765, 2808.5646724757694, 2978.5829983802287, 3005.0984704588536, 3053.0688140117254, 3068.7238148566735, 1220.8718084601167, 1262.4003598290738, 1284.4284293489402, 1309.9738005239938, 1368.1790925444752, 10197.082262596401, 10416.791664166674, 10426.791464170674, 10449.84325235122, 10463.581456741722, 3812.961870381316, 3860.1507668312925, 3934.6195551691385, 3952.3281042222893, 4015.31739604268, 8572.399932004824, 8709.346798990102, 8723.956380218058, 8728.78178045554, 8755.737327880173, 4602.631789456902, 4616.792244349012, 4621.4223222097235, 4626.32918226862, 4628.305754136906, 4213.2837417639075, 4320.308750599889, 4352.608265256142, 4356.6732495063, 4360.41134446852, 3290.0952238134228, 3315.834208289576, 3352.6479719629574, 3375.814330211813, 3378.7634865559667, 975.5951280218771, 995.9402435853872, 998.9200863930868, 1005.1606908231709, 1010.9191264698953, 939.7979434421633, 945.1021529546821, 951.2104952889085, 956.4500412262977, 984.6602921991902, 4937.506249375034, 5170.689758614495, 5392.946070539245, 5438.483344082306, 5539.279893613885, 588.3293045927646, 589.2016317889198, 596.6151832068379, 600.9338972712998, 602.7890238416607, 3432.6910578048096, 3788.412796016622, 3793.79134147619, 3820.2741479119436, 3914.080191155076, 9186.356955013085, 9347.719568412958, 9409.341346105706, 9421.576029078571, 9424.198943089777, 8726.171013753683, 8917.955410222907, 9099.636014559343, 9203.585838637144, 9392.483413412192, 7142.071530700976, 7203.315685009916, 7344.228855970153, 7355.963220183865, 7361.1166660001145, 5058.848234552969, 5133.743312834418, 5204.453532379046, 5236.764345604533, 5428.864278393072, 8250.54621995796, 8278.751637450887, 8374.665013399395, 8442.704505342284, 8537.274331681629, 8463.19599638058, 8468.66125354979, 8489.4481858679, 8497.065322814362, 8582.313414926915, 6692.665366731547, 6889.862202755817, 6893.620850853251, 6901.689423976033, 6902.965485172542, 7149.606771627402, 7368.778936631909, 7416.44376671741, 7422.777316680508, 7430.7027718892805, 6871.759488417882, 6925.896111558432, 6940.063091482585, 6967.407770339578, 6989.0, 6620.238672552526, 6637.53537252407, 6687.230968438497, 6701.865962680621, 6702.19573651165, 5837.211976383134, 5903.940960590339, 5915.08316210988, 5915.40845915405, 5917.053271476543, 3814.275287695329, 4485.37204791323, 4504.459464864237, 4515.1195516873895, 4649.628689368379, 4655.743934083658, 4716.853831506588, 4741.525847415231, 4835.443923948791, 4990.5508504234795], "value": 4026.356197433346, "unit": "score"}, {"name": "prototype.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [382.00297224244736, 390.16115351993216, 390.42022596444275, 396.11270753512105, 402.027094431476, 5120.8975820483565, 5147.948520514794, 5158.81944131955, 5172.551685528048, 5191.662541934772, 4921.63087768417, 5060.115734031603, 5110.514501122397, 5118.7952481900775, 5128.333316668837, 3979.0, 3996.3206254936586, 4006.479157709494, 4008.617027125637, 4014.2172276406186, 4023.7786921719294, 4028.133951200491, 4028.7784171870535, 4030.355143177092, 4031.8992025199314, 3899.493065901429, 3940.940885886714, 3970.2059588082498, 3984.9601503984877, 4015.277250094982, 3940.8817735467983, 3951.8616848410456, 3958.8614398496056, 3959.722819402643, 3965.790433917654, 3455.36075825973, 3502.807345595991, 3502.859885604573, 3515.191505953645, 3547.0, 4902.2156454967035, 4939.199952507717, 4965.205567109281, 5063.518965698253, 5096.439391666913, 4563.726176429424, 4574.656900732459, 4576.290674945389, 4596.586307232332, 4613.677042607019, 5345.492178243061, 5370.8184199476445, 5386.879037536288, 5405.972970135163, 5423.837284881421, 3818.9427158592644, 3913.5890731473355, 3924.725269231155, 3927.6661483773914, 3933.645971862546, 5331.280277162611, 5429.619926605139, 5430.212619170198, 5516.282883225155, 5598.664080155204, 3970.166265084307, 3974.5627980922077, 3984.183242435294, 3989.3018721723583, 3991.3015222335976, 4030.536488303824, 4030.9596904031173, 4047.251258517184, 4051.2910240708347, 4069.2268468990796, 4327.675424343187, 4355.477342718894, 4435.689501734848, 4611.169989401873, 4643.605293550086, 5385.353757549041, 5401.216823560561, 5440.3743569489225, 5442.210879422461, 5449.155380915964, 1371.9519816806564, 1375.7661197596433, 1393.9303034848217, 1397.5318268380004, 1398.7622104242303, 901.340637572919, 940.47333493244, 953.5470651440648, 955.2930831184873, 963.1620490173594, 5036.834161310599, 5057.735566108473, 5065.620078494054, 5072.0, 5075.771590278447, 5367.604422850087, 5378.435264297193, 5379.973100134553, 5383.596230282745, 5393.514583687408, 4867.488913664085, 5052.747362631853, 5062.924056139161, 5083.313752643419, 5248.845254044104, 2858.8856445742354, 2860.828350298989, 2861.7138286171216, 2864.8683769911186, 2865.653143022776, 5552.972235138798, 5579.553635709215, 5581.888362232733, 5605.243292155588, 5616.528727359949, 5330.520253177233, 5537.861553461115, 5665.716714164358, 5666.716664166775, 5679.0, 5718.885622287531, 5741.712914354265, 5744.339400968942, 5746.712664366765, 5747.224124743273, 5369.0069134199275, 5496.560275177896, 5546.557894947262, 5600.523955463831, 5610.13042978339, 5446.645968012067, 5478.589105817, 5531.612787104864, 5553.6390134641115, 5565.666060036411, 5416.647917885325, 5542.750576223999, 5573.804916827891, 5709.8287051388525, 5748.655080695172, 3961.5246170458577, 4021.1354558770004, 4030.314846476165, 4054.0, 4062.4921884764403, 5521.889562208814, 5557.971340460784, 5578.776848925998, 5581.972090139523, 5599.160125981135, 5204.245384419314, 5353.848922481685, 5360.597955153457, 5385.596080293915, 5440.905251478289, 5377.247185394126, 5390.811321603725, 5398.217258497417, 5431.37539182999, 5433.2665090213895, 5405.351357837085, 5412.675239485643, 5417.133258678593, 5422.62041657096, 5425.782968681208, 5183.92224116631, 5362.570994320367, 5385.757640906247, 5446.400895901527, 5460.863478413072, 5285.709285989307, 5299.311089558333, 5301.231321458444, 5341.225522299167, 5363.329583802025], "value": 4128.692503959183, "unit": "score"}, {"name": "yui.html", "lowerIsBetter": false, "alertThreshold": 5.0, "replicates": [736.1350413264416, 770.2413123073771, 804.6298702596805, 829.6515463505327, 839.0895877972399, 15604.063756174637, 16336.509904702849, 16356.509304720863, 16368.345266189368, 16413.83586164138, 14794.926025369858, 15072.773908391355, 15085.924570377132, 15120.773188402183, 15146.697066058672, 760.0157795654627, 779.8206412525122, 796.5021861336664, 797.7247849491945, 807.007380921467, 760.8212070163507, 761.025886864814, 761.3376362564549, 761.6077719974218, 764.109812068939, 833.6457005772553, 839.1692224697535, 841.1420351241729, 841.7727213652311, 841.9452735572199, 830.7756905635445, 831.8544254755394, 832.3216578488543, 832.4589017138878, 832.9458585191943, 602.3584882100549, 604.045607939459, 605.0168476226132, 605.2978544887909, 606.0273261415452, 14530.782038269434, 14551.3451894665, 14559.854401456061, 15207.163606001666, 16024.35902563896, 14226.359813808403, 14835.925820370938, 14876.479323223744, 14976.925115374463, 14984.325705343286, 11800.822987655192, 12137.271763694207, 12262.754744905142, 12401.813972790414, 12453.501859925593, 3628.2017956049626, 3654.6162652921325, 3658.6890114340313, 3795.734298599099, 3802.068493219151, 73514.26485735127, 73610.63194684045, 75104.62447687726, 75660.62169689171, 75859.0, 10568.735781605368, 10737.677869663923, 10787.946060269649, 10789.46052697362, 11074.280171788809, 21452.463688407915, 21704.0, 21705.0, 21709.56580868374, 21744.0, 6779.966100169468, 6922.619255940871, 7028.2620324866175, 7047.964760176269, 7118.359347658736, 3920.2159568086495, 3937.03542632057, 3964.246793109358, 3964.623360780721, 3990.082281075369, 3338.849751761177, 3428.6571342866014, 3462.2556150427276, 3462.4113900636953, 3474.9305013900084, 554.8252300525321, 557.9804706835241, 567.5884983387011, 570.1874828369639, 572.5190839694593, 575.0856138739424, 588.5762251179144, 603.6649659439054, 645.6190847399963, 710.3784188834769, 10077.848832267522, 10568.841467377995, 10570.0, 10620.256582039183, 10631.362118272928, 601.9729112189962, 607.6657838188978, 647.8380404898776, 672.6233309346782, 754.8301632132836, 4856.17445034345, 4954.567383011601, 4995.500449955048, 5037.06073161828, 5044.54599086084, 77318.6134069326, 77945.22054779493, 79126.20873791304, 79184.20815791884, 79631.60184199041, 66569.0, 66891.0, 67777.3222267771, 67830.98253526201, 67985.0, 59773.643007828374, 59934.0, 60262.39737602568, 60497.0, 60743.39256607466, 9099.954500227457, 9489.098535639103, 9491.525423728925, 9542.093501117382, 9544.665936692183, 40625.593744063364, 40937.38593921034, 41188.58811411847, 41601.79199103985, 42299.0, 47394.76302618465, 52491.47508524866, 53322.0, 53427.19859202193, 53495.46504534905, 11230.0, 11854.881451185378, 12323.568675096329, 12621.368931553201, 13217.933910330772, 14191.716165676835, 14336.0, 14394.568162955127, 14863.032816300438, 15197.6200594986, 13979.440822367398, 14036.719265614427, 14484.565463036126, 14645.92677036608, 15465.61335966565, 12714.0, 13499.797503037265, 13565.79651305251, 13843.307834608027, 13989.860101399263, 13756.793648095487, 14321.928390358398, 14404.135751854923, 15116.470923517623, 15118.697626047198, 10444.634437794944, 11461.885381146081, 11692.714547045296, 11760.882391176321, 12437.751244974868, 9728.659496917573, 9928.702138935843, 10213.74465638365, 10832.566697332019, 11055.0], "value": 7078.19582009281, "unit": "score"}]}, {"extraOptions": [], "name": "kraken", "lowerIsBetter": true, "alertThreshold": 2.0, "value": 1517.9, "subtests": [{"name": "ai-astar", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [111.0, 113.0, 115.0, 113.0, 115.0, 115.0, 115.0, 114.0, 114.0, 114.0], "value": 113.9, "unit": "score"}, {"name": "audio-beat-detection", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [145.0, 121.0, 125.0, 165.0, 121.0, 122.0, 128.0, 120.0, 145.0, 121.0], "value": 131.3, "unit": "score"}, {"name": "audio-dft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [161.0, 163.0, 162.0, 162.0, 162.0, 163.0, 164.0, 162.0, 162.0, 162.0], "value": 162.3, "unit": "score"}, {"name": "audio-fft", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [76.0, 76.0, 76.0, 116.0, 78.0, 76.0, 78.0, 77.0, 106.0, 77.0], "value": 83.6, "unit": "score"}, {"name": "audio-oscillator", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 82.0, 110.0, 83.0, 83.0, 83.0, 98.0, 83.0, 82.0, 106.0], "value": 89.4, "unit": "score"}, {"name": "imaging-gaussian-blur", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [114.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0, 113.0], "value": 113.1, "unit": "score"}, {"name": "imaging-darkroom", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [147.0, 147.0, 148.0, 147.0, 147.0, 147.0, 148.0, 147.0, 147.0, 146.0], "value": 147.1, "unit": "score"}, {"name": "imaging-desaturate", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [96.0, 96.0, 95.0, 96.0, 96.0, 96.0, 95.0, 96.0, 96.0, 96.0], "value": 95.8, "unit": "score"}, {"name": "json-parse-financial", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [54.0, 53.0, 53.0, 53.0, 55.0, 55.0, 64.0, 55.0, 55.0, 54.0], "value": 55.1, "unit": "score"}, {"name": "json-stringify-tinderbox", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [64.0, 63.0, 63.0, 63.0, 65.0, 64.0, 63.0, 64.0, 64.0, 64.0], "value": 63.7, "unit": "score"}, {"name": "stanford-crypto-aes", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [84.0, 84.0, 86.0, 84.0, 84.0, 84.0, 88.0, 91.0, 84.0, 86.0], "value": 85.5, "unit": "score"}, {"name": "stanford-crypto-ccm", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [143.0, 133.0, 135.0, 130.0, 130.0, 137.0, 142.0, 133.0, 171.0, 146.0], "value": 140.0, "unit": "score"}, {"name": "stanford-crypto-pbkdf2", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [172.0, 174.0, 174.0, 174.0, 174.0, 173.0, 171.0, 174.0, 169.0, 169.0], "value": 172.4, "unit": "score"}, {"name": "stanford-crypto-sha256-iterative", "lowerIsBetter": true, "alertThreshold": 2.0, "replicates": [67.0, 64.0, 64.0, 65.0, 67.0, 65.0, 64.0, 64.0, 63.0, 64.0], "value": 64.7, "unit": "score"}]}]} 14:05:17 INFO - Return code: 0 14:05:17 INFO - Validating PERFHERDER_DATA against /builds/slave/test/scripts/external_tools/performance-artifact-schema.json 14:05:17 ERROR - # TBPL SUCCESS # 14:05:17 INFO - Running post-action listener: _resource_record_post_action 14:05:17 INFO - [mozharness: 2017-02-17 22:05:17.601574Z] Finished run-tests step (success) 14:05:17 INFO - Running post-run listener: _resource_record_post_run 14:05:17 INFO - Total resource usage - Wall time: 1196s; CPU: 13.0%; Read bytes: 0; Write bytes: 364007424; Read time: 0; Write time: 275324 14:05:17 INFO - TinderboxPrint: CPU usage
13.1% 14:05:17 INFO - TinderboxPrint: I/O read bytes / time
0 / 0 14:05:17 INFO - TinderboxPrint: I/O write bytes / time
364,007,424 / 275,324 14:05:17 INFO - TinderboxPrint: CPU idle
8,295.9 (86.9%) 14:05:17 INFO - TinderboxPrint: CPU user
1,201.3 (12.6%) 14:05:17 INFO - TinderboxPrint: Swap in / out
0 / 0 14:05:17 INFO - install - Wall time: 17s; CPU: 13.0%; Read bytes: 0; Write bytes: 109662208; Read time: 0; Write time: 89028 14:05:17 INFO - run-tests - Wall time: 1179s; CPU: 13.0%; Read bytes: 0; Write bytes: 253960192; Read time: 0; Write time: 186272 14:05:18 INFO - Running post-run listener: _upload_blobber_files 14:05:18 INFO - Blob upload gear active. 14:05:18 INFO - Preparing to upload files from /builds/slave/test/build/blobber_upload_dir. 14:05:18 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 14:05:18 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', '-d', '/builds/slave/test/build/blobber_upload_dir', '--output-manifest', '/builds/slave/test/build/uploaded_files.json'] 14:05:18 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 -d /builds/slave/test/build/blobber_upload_dir --output-manifest /builds/slave/test/build/uploaded_files.json 14:05:18 INFO - (blobuploader) - INFO - Open directory for files ... 14:05:18 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/resource-usage.json ... 14:05:18 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:05:18 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:05:21 INFO - (blobuploader) - INFO - TinderboxPrint: resource-usage.json: uploaded 14:05:21 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:05:21 INFO - (blobuploader) - INFO - Done attempting. 14:05:21 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/perfherder-data.json ... 14:05:21 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:05:21 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:05:22 INFO - (blobuploader) - INFO - TinderboxPrint: perfherder-data.json: uploaded 14:05:22 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:05:22 INFO - (blobuploader) - INFO - Done attempting. 14:05:22 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_raw.log ... 14:05:22 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:05:22 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:05:23 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_raw.log: uploaded 14:05:23 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:05:23 INFO - (blobuploader) - INFO - Done attempting. 14:05:23 INFO - (blobuploader) - INFO - Uploading /builds/slave/test/build/blobber_upload_dir/dromaeojs_errorsummary.log ... 14:05:23 INFO - (blobuploader) - INFO - Using https://blobupload.elasticbeanstalk.com 14:05:23 INFO - (blobuploader) - INFO - Uploading, attempt #1. 14:05:24 INFO - (blobuploader) - INFO - TinderboxPrint: dromaeojs_errorsummary.log: uploaded 14:05:24 INFO - (blobuploader) - INFO - Blobserver returned 202. File uploaded! 14:05:24 INFO - (blobuploader) - INFO - Done attempting. 14:05:24 INFO - (blobuploader) - INFO - Iteration through files over. 14:05:24 INFO - Return code: 0 14:05:24 INFO - rmtree: /builds/slave/test/build/uploaded_files.json 14:05:24 INFO - retry: Calling remove with args: ('/builds/slave/test/build/uploaded_files.json',), kwargs: {}, attempt #1 14:05:24 INFO - Setting buildbot property blobber_files to {"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a907df45fd73dd9b96100d48b6ad41203a74d775b1160af799e9775c9687b94f8671eb681a29e8cb73f7a63d595124cfff6378f4788820e21acb9358f6ae34a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f94ca163b2ada9545c2d1d4893a6b44ab0dc7435483f049bd19e35a37aaa9f01163cd02aa67739b82b75616481f144defda0e83d81a8cbd8c3843304af4075a2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72822c682b9309f1f32c5fe801c6cd16c94bff5075d2ddfe604623a7ddf297d445d553acdc508123fbde5ff454d56e226fef30bdb686e912b612a0d66b788b24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f21c547b10bc5c5cfcb610bce5ffe7cdaba2267006e89b184e29573c03c1c98cf7b66ba973332607231c98337b4d63d173b0ab8a663a9209a3bb1e54228dd63"} 14:05:24 INFO - Writing buildbot properties ['blobber_files'] to /builds/slave/test/properties/blobber_files 14:05:24 INFO - Writing to file /builds/slave/test/properties/blobber_files 14:05:24 INFO - Contents: 14:05:24 INFO - blobber_files:{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a907df45fd73dd9b96100d48b6ad41203a74d775b1160af799e9775c9687b94f8671eb681a29e8cb73f7a63d595124cfff6378f4788820e21acb9358f6ae34a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f94ca163b2ada9545c2d1d4893a6b44ab0dc7435483f049bd19e35a37aaa9f01163cd02aa67739b82b75616481f144defda0e83d81a8cbd8c3843304af4075a2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72822c682b9309f1f32c5fe801c6cd16c94bff5075d2ddfe604623a7ddf297d445d553acdc508123fbde5ff454d56e226fef30bdb686e912b612a0d66b788b24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f21c547b10bc5c5cfcb610bce5ffe7cdaba2267006e89b184e29573c03c1c98cf7b66ba973332607231c98337b4d63d173b0ab8a663a9209a3bb1e54228dd63"} 14:05:24 INFO - Running post-run listener: copy_logs_to_upload_dir 14:05:24 INFO - Copying logs to upload dir... 14:05:24 INFO - mkdir: /builds/slave/test/build/upload/logs 14:05:24 INFO - Copying logs to upload dir... program finished with exit code 0 elapsedTime=1254.611361 ========= master_lag: 0.00 ========= ========= Finished '/tools/buildbot/bin/python scripts/scripts/talos_script.py ...' (results: 0, elapsed: 20 mins, 54 secs) (at 2017-02-17 14:05:23.755912) ========= ========= Started set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:05:23.759812) ========= 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=ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490 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/sha512/9a907df45fd73dd9b96100d48b6ad41203a74d775b1160af799e9775c9687b94f8671eb681a29e8cb73f7a63d595124cfff6378f4788820e21acb9358f6ae34a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f94ca163b2ada9545c2d1d4893a6b44ab0dc7435483f049bd19e35a37aaa9f01163cd02aa67739b82b75616481f144defda0e83d81a8cbd8c3843304af4075a2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72822c682b9309f1f32c5fe801c6cd16c94bff5075d2ddfe604623a7ddf297d445d553acdc508123fbde5ff454d56e226fef30bdb686e912b612a0d66b788b24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f21c547b10bc5c5cfcb610bce5ffe7cdaba2267006e89b184e29573c03c1c98cf7b66ba973332607231c98337b4d63d173b0ab8a663a9209a3bb1e54228dd63"} build_url:https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2 program finished with exit code 0 elapsedTime=0.011445 build_url: 'https://queue.taskcluster.net/v1/task/Acd_kqAOQcCeZfTrp5-fiw/artifacts/public/build/target.tar.bz2' blobber_files: '{"perfherder-data.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/9a907df45fd73dd9b96100d48b6ad41203a74d775b1160af799e9775c9687b94f8671eb681a29e8cb73f7a63d595124cfff6378f4788820e21acb9358f6ae34a", "resource-usage.json": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/f94ca163b2ada9545c2d1d4893a6b44ab0dc7435483f049bd19e35a37aaa9f01163cd02aa67739b82b75616481f144defda0e83d81a8cbd8c3843304af4075a2", "dromaeojs_raw.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/72822c682b9309f1f32c5fe801c6cd16c94bff5075d2ddfe604623a7ddf297d445d553acdc508123fbde5ff454d56e226fef30bdb686e912b612a0d66b788b24", "dromaeojs_errorsummary.log": "http://mozilla-releng-blobs.s3.amazonaws.com/blobs/Firefox/sha512/0f21c547b10bc5c5cfcb610bce5ffe7cdaba2267006e89b184e29573c03c1c98cf7b66ba973332607231c98337b4d63d173b0ab8a663a9209a3bb1e54228dd63"}' ========= master_lag: 0.01 ========= ========= Finished set props: build_url blobber_files (results: 0, elapsed: 0 secs) (at 2017-02-17 14:05:23.784743) ========= ========= Started 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:05:23.785108) ========= 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=ea65d483da93c827905c2090000001f5-1487367867.102641-1969482490 XPCOM_DEBUG_BREAK=warn _=/tools/buildbot/bin/python using PTY: False program finished with exit code 0 elapsedTime=0.004515 ========= master_lag: 0.08 ========= ========= Finished 'rm -f ...' (results: 0, elapsed: 0 secs) (at 2017-02-17 14:05:23.869241) ========= ========= Started reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:05:23.869584) ========= ========= Finished reboot skipped (results: 3, elapsed: 0 secs) (at 2017-02-17 14:05:23.869889) ========= ========= Total master_lag: 0.16 =========